-- Logs begin at Fri 2025-01-10 09:01:57 EET, end at Mon 2025-01-20 00:04:56 EET. -- Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:03:03 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:03:03 rivo-nikos volumio[3108]: verbose: New Socket.io Connection to 192.168.1.11:3000 from 192.168.1.184 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 20 00:03:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 20 00:03:06 rivo-nikos volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 00:03:06 rivo-nikos volumio[3108]: (node:3108) UnhandledPromiseRejectionWarning: Error: The device code has expired, restarting auth flow. Jan 20 00:03:06 rivo-nikos volumio[3108]: at OAuth. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:22224:82) Jan 20 00:03:06 rivo-nikos volumio[3108]: at Generator.next () Jan 20 00:03:06 rivo-nikos volumio[3108]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Jan 20 00:03:06 rivo-nikos volumio[3108]: at runMicrotasks () Jan 20 00:03:06 rivo-nikos volumio[3108]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jan 20 00:03:06 rivo-nikos volumio[3108]: (node:3108) 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: 4) Jan 20 00:03:06 rivo-nikos volumio[3108]: error: [youtube2] Error in ConfigModel.#fetchAccountMenu(): Request to https://www.youtube.com/youtubei/vnull/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/vnull/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Jan 20 00:03:06 rivo-nikos volumio[3108]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:22010:13) Jan 20 00:03:06 rivo-nikos volumio[3108]: at Generator.next () Jan 20 00:03:06 rivo-nikos volumio[3108]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Jan 20 00:03:06 rivo-nikos volumio[3108]: at runMicrotasks () Jan 20 00:03:06 rivo-nikos volumio[3108]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jan 20 00:03:06 rivo-nikos volumio[3108]: (node:3108) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Jan 20 00:03:06 rivo-nikos volumio[3108]: at Function.entries () Jan 20 00:03:06 rivo-nikos volumio[3108]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Jan 20 00:03:06 rivo-nikos volumio[3108]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Jan 20 00:03:06 rivo-nikos volumio[3108]: at runMicrotasks () Jan 20 00:03:06 rivo-nikos volumio[3108]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jan 20 00:03:06 rivo-nikos volumio[3108]: (node:3108) 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: 5) Jan 20 00:03:07 rivo-nikos volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 00:03:07 rivo-nikos volumio[3108]: error: [youtube2] Error in ConfigModel.#fetchAccountMenu(): Request to https://www.youtube.com/youtubei/vnull/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/vnull/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Jan 20 00:03:07 rivo-nikos volumio[3108]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:22010:13) Jan 20 00:03:07 rivo-nikos volumio[3108]: at Generator.next () Jan 20 00:03:07 rivo-nikos volumio[3108]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Jan 20 00:03:07 rivo-nikos volumio[3108]: at runMicrotasks () Jan 20 00:03:07 rivo-nikos volumio[3108]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jan 20 00:03:07 rivo-nikos volumio[3108]: (node:3108) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Jan 20 00:03:07 rivo-nikos volumio[3108]: at Function.entries () Jan 20 00:03:07 rivo-nikos volumio[3108]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Jan 20 00:03:07 rivo-nikos volumio[3108]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Jan 20 00:03:07 rivo-nikos volumio[3108]: at runMicrotasks () Jan 20 00:03:07 rivo-nikos volumio[3108]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jan 20 00:03:07 rivo-nikos volumio[3108]: (node:3108) 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: 6) Jan 20 00:03:09 rivo-nikos sudo[3730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 00:03:09 rivo-nikos sudo[3730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 00:03:09 rivo-nikos sudo[3730]: pam_unix(sudo:session): session closed for user root Jan 20 00:03:09 rivo-nikos sudo[3734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 00:03:09 rivo-nikos sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 00:03:09 rivo-nikos sudo[3734]: pam_unix(sudo:session): session closed for user root Jan 20 00:03:09 rivo-nikos volumio[3108]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.184 UA: Mozilla/5.0 (Linux; Android 14; SM-S928B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.261 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 20 00:03:09 rivo-nikos sudo[3738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 00:03:09 rivo-nikos sudo[3738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 00:03:09 rivo-nikos sudo[3738]: pam_unix(sudo:session): session closed for user root Jan 20 00:03:09 rivo-nikos sudo[3741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 00:03:09 rivo-nikos sudo[3741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 00:03:09 rivo-nikos sudo[3741]: pam_unix(sudo:session): session closed for user root Jan 20 00:03:09 rivo-nikos volumio[3108]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.184 UA: Mozilla/5.0 (Linux; Android 14; SM-S928B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.261 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetVisibleSources Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 00:03:09 rivo-nikos volumio[3108]: info: Received Get System Info Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:03:09 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:09 rivo-nikos volumio[3108]: info: Listing playlists Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 20 00:03:09 rivo-nikos volumio[3108]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 00:03:09 rivo-nikos volumiologrotate[2851]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Jan 20 00:03:09 rivo-nikos volumiologrotate[2851]: ls: cannot access 'NIKOS': No such file or directory Jan 20 00:03:11 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 20 00:03:11 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 20 00:03:11 rivo-nikos volumio[3108]: info: Received Get System Info Jan 20 00:03:11 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:03:11 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:03:11 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:03:11 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:11 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:11 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Received Get System Info Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:12 rivo-nikos volumio[3108]: info: [1737324192440] ControllerTidal::clearAddPlayTrack Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:12 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Jan 20 00:03:12 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:12 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413621&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:12 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Jan 20 00:03:12 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:12 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:12 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413621&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:12 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413621&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:12 rivo-nikos volumio[3108]: info: ------------------------------ 10ms Jan 20 00:03:12 rivo-nikos volumio[3108]: info: sendMpdCommand play took 8 milliseconds Jan 20 00:03:12 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:12 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:12 rivo-nikos volumio[3108]: info: Jan 20 00:03:12 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:12 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:12 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:12 rivo-nikos volumio[3108]: info: sendMpdCommand status took 6 milliseconds Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:12 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:12 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:12 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413621&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413621&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:12 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 0 Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:12 rivo-nikos volumio[3108]: info: ------------------------------ 30ms Jan 20 00:03:13 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 1 Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 1 Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 1 Jan 20 00:03:13 rivo-nikos volumio[3108]: info: [1737324193481] ControllerTidal::clearAddPlayTrack Jan 20 00:03:13 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:13 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 2 milliseconds Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:13 rivo-nikos volumio[3108]: info: Jan 20 00:03:13 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:13 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:13 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413622&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:13 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:13 rivo-nikos volumio[3108]: info: Jan 20 00:03:13 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:13 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:13 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:13 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413622&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:13 rivo-nikos volumio[3108]: info: ------------------------------ 2ms Jan 20 00:03:13 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:13 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413622&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:13 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:13 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:13 rivo-nikos volumio[3108]: info: Jan 20 00:03:13 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:13 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:13 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:13 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:13 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:13 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:13 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 1 Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413622&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413622&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:13 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 1 Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:13 rivo-nikos volumio[3108]: info: ------------------------------ 26ms Jan 20 00:03:14 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 2 Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 2 Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 2 Jan 20 00:03:14 rivo-nikos volumio[3108]: info: [1737324194518] ControllerTidal::clearAddPlayTrack Jan 20 00:03:14 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:14 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:14 rivo-nikos volumio[3108]: info: Jan 20 00:03:14 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:14 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:14 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413623&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:14 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:14 rivo-nikos volumio[3108]: info: Jan 20 00:03:14 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:14 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:14 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:14 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413623&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:14 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413623&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:14 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:14 rivo-nikos volumio[3108]: info: sendMpdCommand play took 8 milliseconds Jan 20 00:03:14 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:14 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:14 rivo-nikos volumio[3108]: info: Jan 20 00:03:14 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:14 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:14 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:14 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:14 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:14 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:14 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 2 Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413623&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413623&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:14 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 2 Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:14 rivo-nikos volumio[3108]: info: ------------------------------ 24ms Jan 20 00:03:15 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 3 Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 3 Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 3 Jan 20 00:03:15 rivo-nikos volumio[3108]: info: [1737324195556] ControllerTidal::clearAddPlayTrack Jan 20 00:03:15 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:15 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:15 rivo-nikos volumio[3108]: info: Jan 20 00:03:15 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:15 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:15 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:15 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:15 rivo-nikos volumio[3108]: info: Jan 20 00:03:15 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:15 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:15 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:15 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:15 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:15 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:15 rivo-nikos volumio[3108]: info: sendMpdCommand play took 15 milliseconds Jan 20 00:03:15 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:15 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:15 rivo-nikos volumio[3108]: info: Jan 20 00:03:15 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:15 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:15 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:15 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:15 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:15 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:15 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 3 Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413624&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:15 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 3 Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:15 rivo-nikos volumio[3108]: info: ------------------------------ 32ms Jan 20 00:03:16 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 4 Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 4 Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 4 Jan 20 00:03:16 rivo-nikos volumio[3108]: info: [1737324196597] ControllerTidal::clearAddPlayTrack Jan 20 00:03:16 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:16 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 3 milliseconds Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:16 rivo-nikos volumio[3108]: info: Jan 20 00:03:16 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:16 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:16 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:16 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:16 rivo-nikos volumio[3108]: info: Jan 20 00:03:16 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:16 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:16 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:16 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:16 rivo-nikos volumio[3108]: info: ------------------------------ 2ms Jan 20 00:03:16 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:16 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:16 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:16 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:16 rivo-nikos volumio[3108]: info: Jan 20 00:03:16 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:16 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:16 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:16 rivo-nikos volumio[3108]: info: sendMpdCommand status took 4 milliseconds Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:16 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:16 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:16 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 4 Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413625&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:16 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 4 Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:16 rivo-nikos volumio[3108]: info: ------------------------------ 23ms Jan 20 00:03:17 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 5 Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 5 Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 5 Jan 20 00:03:17 rivo-nikos volumio[3108]: info: [1737324197641] ControllerTidal::clearAddPlayTrack Jan 20 00:03:17 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:17 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 7 milliseconds Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:17 rivo-nikos volumio[3108]: info: Jan 20 00:03:17 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:17 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:17 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:17 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:17 rivo-nikos volumio[3108]: info: Jan 20 00:03:17 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:17 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:17 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:17 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:17 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:17 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:17 rivo-nikos volumio[3108]: info: sendMpdCommand play took 3 milliseconds Jan 20 00:03:17 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:17 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:17 rivo-nikos volumio[3108]: info: Jan 20 00:03:17 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:17 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:17 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:17 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:17 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:17 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:17 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 5 Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413626&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:17 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 5 Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:17 rivo-nikos volumio[3108]: info: ------------------------------ 27ms Jan 20 00:03:18 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 6 Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 6 Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 6 Jan 20 00:03:18 rivo-nikos volumio[3108]: info: [1737324198684] ControllerTidal::clearAddPlayTrack Jan 20 00:03:18 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:18 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:18 rivo-nikos volumio[3108]: info: Jan 20 00:03:18 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:18 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:18 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:18 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:18 rivo-nikos volumio[3108]: info: Jan 20 00:03:18 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:18 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:18 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:18 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:18 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:18 rivo-nikos volumio[3108]: info: sendMpdCommand play took 3 milliseconds Jan 20 00:03:18 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:18 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:18 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:18 rivo-nikos volumio[3108]: info: Jan 20 00:03:18 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:18 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:18 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:18 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:18 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:18 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:18 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 6 Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413627&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:18 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 6 Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:18 rivo-nikos volumio[3108]: info: ------------------------------ 25ms Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetQueue Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::getQueue Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CorePlayQueue::getQueue Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 00:03:19 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 7 Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 7 Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 7 Jan 20 00:03:19 rivo-nikos volumio[3108]: info: [1737324199716] ControllerTidal::clearAddPlayTrack Jan 20 00:03:19 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:19 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:19 rivo-nikos volumio[3108]: info: Jan 20 00:03:19 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:19 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:19 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:19 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:19 rivo-nikos volumio[3108]: info: Jan 20 00:03:19 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:19 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:19 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:19 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:19 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:19 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:19 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:19 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:19 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:19 rivo-nikos volumio[3108]: info: Jan 20 00:03:19 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:19 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:19 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:19 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:19 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:19 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:19 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 7 Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413628&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:19 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 7 Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:19 rivo-nikos volumio[3108]: info: ------------------------------ 25ms Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 8 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 8 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 8 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: [1737324200750] ControllerTidal::clearAddPlayTrack Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Jan 20 00:03:20 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:20 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Jan 20 00:03:20 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ------------------------------ 6ms Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE" took 6 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ------------------------------ 2ms Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:20 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:20 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Jan 20 00:03:20 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:20 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand status took 4 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 8 Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413629&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 8 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ------------------------------ 24ms Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 3 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 3 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 3 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: [1737324200852] ControllerTidal::clearAddPlayTrack Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 4 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Jan 20 00:03:20 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:20 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Jan 20 00:03:20 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ------------------------------ 2ms Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:20 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:20 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:20 rivo-nikos volumio[3108]: info: Jan 20 00:03:20 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:20 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand status took 4 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 3 Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413624&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413624&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:20 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 3 Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:20 rivo-nikos volumio[3108]: info: ------------------------------ 28ms Jan 20 00:03:21 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 4 Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 4 Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 4 Jan 20 00:03:21 rivo-nikos volumio[3108]: info: [1737324201888] ControllerTidal::clearAddPlayTrack Jan 20 00:03:21 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:21 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:21 rivo-nikos volumio[3108]: info: Jan 20 00:03:21 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:21 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:21 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:21 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:21 rivo-nikos volumio[3108]: info: Jan 20 00:03:21 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:21 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:21 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:21 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:21 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:21 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:21 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:21 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:21 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:21 rivo-nikos volumio[3108]: info: Jan 20 00:03:21 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:21 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:21 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:21 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:21 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 2 milliseconds Jan 20 00:03:21 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:21 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 4 Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413625&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413625&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:21 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 4 Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:21 rivo-nikos volumio[3108]: info: ------------------------------ 24ms Jan 20 00:03:22 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 5 Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 5 Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 5 Jan 20 00:03:22 rivo-nikos volumio[3108]: info: [1737324202921] ControllerTidal::clearAddPlayTrack Jan 20 00:03:22 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:22 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:22 rivo-nikos volumio[3108]: info: Jan 20 00:03:22 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:22 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:22 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:22 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:22 rivo-nikos volumio[3108]: info: Jan 20 00:03:22 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:22 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:22 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:22 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:22 rivo-nikos volumio[3108]: info: ------------------------------ 2ms Jan 20 00:03:22 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:22 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:22 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:22 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:22 rivo-nikos volumio[3108]: info: Jan 20 00:03:22 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:22 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:22 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:22 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:22 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:22 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:22 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 5 Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413626&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413626&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:22 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 5 Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:22 rivo-nikos volumio[3108]: info: ------------------------------ 33ms Jan 20 00:03:23 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 6 Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 6 Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 6 Jan 20 00:03:23 rivo-nikos volumio[3108]: info: [1737324203956] ControllerTidal::clearAddPlayTrack Jan 20 00:03:23 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:23 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:23 rivo-nikos volumio[3108]: info: Jan 20 00:03:23 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:23 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:23 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:23 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:23 rivo-nikos volumio[3108]: info: Jan 20 00:03:23 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:23 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:23 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:23 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:23 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:23 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:23 rivo-nikos volumio[3108]: info: sendMpdCommand play took 8 milliseconds Jan 20 00:03:23 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:23 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:23 rivo-nikos volumio[3108]: info: Jan 20 00:03:23 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:23 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:23 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:23 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:23 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:23 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 20 00:03:23 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:23 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:23 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 6 Jan 20 00:03:24 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413627&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413627&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:24 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 6 Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:24 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:24 rivo-nikos volumio[3108]: info: ------------------------------ 28ms Jan 20 00:03:24 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 7 Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 7 Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:24 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 7 Jan 20 00:03:24 rivo-nikos volumio[3108]: info: [1737324204993] ControllerTidal::clearAddPlayTrack Jan 20 00:03:24 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:24 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:24 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 2 milliseconds Jan 20 00:03:24 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:24 rivo-nikos volumio[3108]: info: Jan 20 00:03:24 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:24 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:24 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:24 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:24 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:25 rivo-nikos volumio[3108]: info: Jan 20 00:03:25 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:25 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:25 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:25 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:25 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:25 rivo-nikos volumio[3108]: info: sendMpdCommand play took 3 milliseconds Jan 20 00:03:25 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:25 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:25 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:25 rivo-nikos volumio[3108]: info: Jan 20 00:03:25 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:25 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:25 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:25 rivo-nikos volumio[3108]: info: sendMpdCommand status took 6 milliseconds Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:25 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:25 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:25 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 7 Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413628&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413628&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:25 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 7 Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:25 rivo-nikos volumio[3108]: info: ------------------------------ 29ms Jan 20 00:03:26 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 8 Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 8 Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 8 Jan 20 00:03:26 rivo-nikos volumio[3108]: info: [1737324206033] ControllerTidal::clearAddPlayTrack Jan 20 00:03:26 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:26 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 2 milliseconds Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:26 rivo-nikos volumio[3108]: info: Jan 20 00:03:26 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:26 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:26 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 1 milliseconds Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:26 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:26 rivo-nikos volumio[3108]: info: Jan 20 00:03:26 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:26 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:26 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:26 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:26 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:26 rivo-nikos volumio[3108]: info: ------------------------------ 5ms Jan 20 00:03:26 rivo-nikos volumio[3108]: info: sendMpdCommand play took 3 milliseconds Jan 20 00:03:26 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:26 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:26 rivo-nikos volumio[3108]: info: Jan 20 00:03:26 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:26 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:26 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:26 rivo-nikos volumio[3108]: info: sendMpdCommand status took 4 milliseconds Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:26 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:26 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:26 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 8 Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413629&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413629&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:26 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 8 Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:26 rivo-nikos volumio[3108]: info: ------------------------------ 29ms Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 9 Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 9 Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 9 Jan 20 00:03:27 rivo-nikos volumio[3108]: info: [1737324207069] ControllerTidal::clearAddPlayTrack Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:27 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Jan 20 00:03:27 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:27 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413630&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:27 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Jan 20 00:03:27 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:27 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:27 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413630&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:27 rivo-nikos volumio[3108]: info: ------------------------------ 5ms Jan 20 00:03:27 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413630&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:27 rivo-nikos volumio[3108]: info: sendMpdCommand play took 4 milliseconds Jan 20 00:03:27 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:27 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Jan 20 00:03:27 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:27 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:27 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:27 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:27 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:27 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:27 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 9 Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413630&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413630&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:27 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 9 Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: ------------------------------ 33ms Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Received Get System Version Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Received Get System Info Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:03:27 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:27 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:03:28 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 10 Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 10 Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 10 Jan 20 00:03:28 rivo-nikos volumio[3108]: info: [1737324208105] ControllerTidal::clearAddPlayTrack Jan 20 00:03:28 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:28 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:28 rivo-nikos volumio[3108]: info: Jan 20 00:03:28 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:28 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:28 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 4 milliseconds Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413631&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:28 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:28 rivo-nikos volumio[3108]: info: Jan 20 00:03:28 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:28 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:28 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:28 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413631&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:28 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413631&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:28 rivo-nikos volumio[3108]: info: ------------------------------ 8ms Jan 20 00:03:28 rivo-nikos volumio[3108]: info: sendMpdCommand play took 7 milliseconds Jan 20 00:03:28 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:28 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:28 rivo-nikos volumio[3108]: info: Jan 20 00:03:28 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:28 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:28 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:28 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:28 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:28 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:28 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 10 Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413631&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413631&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:28 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 10 Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:28 rivo-nikos volumio[3108]: info: ------------------------------ 28ms Jan 20 00:03:29 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 11 Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 11 Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 11 Jan 20 00:03:29 rivo-nikos volumio[3108]: info: [1737324209158] ControllerTidal::clearAddPlayTrack Jan 20 00:03:29 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:29 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 2 milliseconds Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:29 rivo-nikos volumio[3108]: info: Jan 20 00:03:29 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:29 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:29 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413632&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:29 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:29 rivo-nikos volumio[3108]: info: Jan 20 00:03:29 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:29 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:29 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:29 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413632&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:29 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:29 rivo-nikos volumio[3108]: info: sendMpdCommand play took 3 milliseconds Jan 20 00:03:29 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413632&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:29 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:29 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:29 rivo-nikos volumio[3108]: info: Jan 20 00:03:29 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:29 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:29 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:29 rivo-nikos volumio[3108]: info: sendMpdCommand status took 6 milliseconds Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:29 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:29 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:29 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 11 Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413632&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413632&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:29 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 11 Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:29 rivo-nikos volumio[3108]: info: ------------------------------ 28ms Jan 20 00:03:30 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 12 Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 12 Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 12 Jan 20 00:03:30 rivo-nikos volumio[3108]: info: [1737324210195] ControllerTidal::clearAddPlayTrack Jan 20 00:03:30 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:30 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 1 milliseconds Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:30 rivo-nikos volumio[3108]: info: Jan 20 00:03:30 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:30 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:30 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413633&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:30 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:30 rivo-nikos volumio[3108]: info: Jan 20 00:03:30 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:30 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:30 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:30 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413633&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:30 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:30 rivo-nikos volumio[3108]: info: sendMpdCommand play took 3 milliseconds Jan 20 00:03:30 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413633&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:30 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:30 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:30 rivo-nikos volumio[3108]: info: Jan 20 00:03:30 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:30 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:30 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:30 rivo-nikos volumio[3108]: info: sendMpdCommand status took 5 milliseconds Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:30 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:30 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:30 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 12 Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413633&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413633&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:30 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 12 Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:30 rivo-nikos volumio[3108]: info: ------------------------------ 28ms Jan 20 00:03:31 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 13 Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 13 Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 13 Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetVisibleSources Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 20 00:03:31 rivo-nikos volumio[3108]: info: [1737324211232] ControllerTidal::clearAddPlayTrack Jan 20 00:03:31 rivo-nikos volumio[3108]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 00:03:31 rivo-nikos volumio[3108]: info: sendMpdCommand stop took 2 milliseconds Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 00:03:31 rivo-nikos volumio[3108]: info: Jan 20 00:03:31 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:31 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:31 rivo-nikos volumio[3108]: info: sendMpdCommand clear took 2 milliseconds Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413634&soundQuality=HIGHEST_POSSIBLE" Jan 20 00:03:31 rivo-nikos volumio[3108]: error: updateQueue error: null Jan 20 00:03:31 rivo-nikos volumio[3108]: info: Jan 20 00:03:31 rivo-nikos volumio[3108]: ---------------------------- MPD announces system playlist update Jan 20 00:03:31 rivo-nikos volumio[3108]: info: Ignoring MPD Status Update Jan 20 00:03:31 rivo-nikos volumio[3108]: info: ------------------------------ 4ms Jan 20 00:03:31 rivo-nikos volumio[3108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/78413634&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Jan 20 00:03:31 rivo-nikos volumio[3108]: info: ------------------------------ 3ms Jan 20 00:03:31 rivo-nikos volumio[3108]: info: sendMpdCommand play took 2 milliseconds Jan 20 00:03:31 rivo-nikos volumio[3108]: STREAMING PROXY: Handling url /?data=tidal://song/78413634&soundQuality=HIGHEST_POSSIBLE Jan 20 00:03:31 rivo-nikos volumio[3108]: info: No valid Plugin REST Endpoint: getStreamUrltidal Jan 20 00:03:31 rivo-nikos volumio[3108]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Jan 20 00:03:31 rivo-nikos volumio[3108]: info: Jan 20 00:03:31 rivo-nikos volumio[3108]: ---------------------------- MPD announces state update: player Jan 20 00:03:31 rivo-nikos volumio[3108]: info: ControllerMpd::getState Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 20 00:03:31 rivo-nikos volumio[3108]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 20 00:03:31 rivo-nikos volumio[3108]: info: sendMpdCommand status took 4 milliseconds Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseState Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 00:03:31 rivo-nikos volumio[3108]: info: sendMpdCommand clearerror took 3 milliseconds Jan 20 00:03:31 rivo-nikos volumio[3108]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 20 00:03:31 rivo-nikos volumio[3108]: info: ControllerMpd::pushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::servicePushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 13 Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78413634&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/78413634&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 20 00:03:31 rivo-nikos volumio[3108]: verbose: CURRENT POSITION 13 Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState stateService stop Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: No code Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreStateMachine::pushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 20 00:03:31 rivo-nikos volumio[3108]: info: ------------------------------ 26ms Jan 20 00:03:32 rivo-nikos volumio[3108]: info: Moving to next track after playback error Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioPlay Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CoreStateMachine::play index 14 Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CoreStateMachine::stop Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CoreStateMachine::play index undefined Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 00:03:32 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 14 Jan 20 00:03:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:03:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:03:33 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:03:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:03:33 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:03:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:03:57 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 20 00:03:57 rivo-nikos volumio[3108]: info: browseTIDALUri took 276 milliseconds Jan 20 00:03:57 rivo-nikos volumio[3108]: info: Preload queue cleared Jan 20 00:03:57 rivo-nikos volumio[3108]: info: Preload queue cleared Jan 20 00:04:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:04:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:04:03 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:04:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:04:03 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:04:03 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:04:09 rivo-nikos volumiologrotate[2851]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Jan 20 00:04:09 rivo-nikos volumiologrotate[2851]: ls: cannot access 'NIKOS': No such file or directory Jan 20 00:04:15 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 20 00:04:15 rivo-nikos volumio[3108]: info: browseTIDALUri took 274 milliseconds Jan 20 00:04:15 rivo-nikos volumio[3108]: info: Preload queue cleared Jan 20 00:04:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 00:04:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 00:04:33 rivo-nikos volumio[3108]: info: Discovery: Getting this device information Jan 20 00:04:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::volumioGetState Jan 20 00:04:33 rivo-nikos volumio[3108]: info: CorePlayQueue::getTrack 0 Jan 20 00:04:33 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 00:04:49 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 20 00:04:49 rivo-nikos volumio[3108]: info: browseTIDALUri took 286 milliseconds Jan 20 00:04:49 rivo-nikos volumio[3108]: info: Preload queue cleared Jan 20 00:04:50 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 20 00:04:51 rivo-nikos volumio[3108]: info: browseTIDALUri took 411 milliseconds Jan 20 00:04:51 rivo-nikos volumio[3108]: info: Preload queue cleared Jan 20 00:04:53 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 20 00:04:54 rivo-nikos volumio[3108]: info: browseTIDALUri took 577 milliseconds Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preload queue cleared Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082436 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/152587296 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/152587253 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082432 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/152587255 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/70929823 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082513 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082431 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/53343008 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/170942527 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082430 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/116748846 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/45185771 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082433 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/130696861 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/49156959 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/77624409 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/152587257 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/75149374 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/78374753 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082434 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/152587256 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/92419191 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/168671550 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Preloading song: tidal://song/120082437 Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082436 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/152587296 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/152587253 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Executing endpoint getSimilarAlbums Jan 20 00:04:54 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082432 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/152587255 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Executing endpoint metavolumio Jan 20 00:04:54 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Executing endpoint metavolumio Jan 20 00:04:54 rivo-nikos volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/70929823 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082513 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082431 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/53343008 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/170942527 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: explodeTIDALUri took 453 milliseconds Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082430 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/116748846 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/45185771 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: explodeTIDALUri took 514 milliseconds Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082433 in service tidal Jan 20 00:04:54 rivo-nikos volumio[3108]: info: explodeTIDALUri took 490 milliseconds Jan 20 00:04:54 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/130696861 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 512 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/49156959 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 784 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/120082436 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 519 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/120082513 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/77624409 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 430 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/152587257 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 743 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/120082432 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/75149374 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/78374753 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 487 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/120082430 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082434 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/152587256 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 484 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/116748846 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 447 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/120082433 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 504 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/92419191 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 768 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/120082431 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/168671550 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: Exploding uri tidal://song/120082437 in service tidal Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 828 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 474 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: error: TIDAL Browse Error: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: error: Commandrouter: Cannot explode uri tidal://song/120082434 from service tidal: Not Found Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 779 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 771 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 742 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: info: explodeTIDALUri took 522 milliseconds Jan 20 00:04:55 rivo-nikos volumio[3108]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 00:04:55 rivo-nikos volumio[3108]: Error: connect ENETUNREACH 151.101.130.79:443 Jan 20 00:04:55 rivo-nikos volumio[3108]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 20 00:04:55 rivo-nikos volumio[3108]: errno: -101, Jan 20 00:04:55 rivo-nikos volumio[3108]: code: 'ENETUNREACH', Jan 20 00:04:55 rivo-nikos volumio[3108]: syscall: 'connect', Jan 20 00:04:55 rivo-nikos volumio[3108]: address: '151.101.130.79', Jan 20 00:04:55 rivo-nikos volumio[3108]: port: 443 Jan 20 00:04:55 rivo-nikos volumio[3108]: } Jan 20 00:04:55 rivo-nikos volumio[3108]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 00:04:56 rivo-nikos sudo[4080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 00:03 Jan 20 00:04:56 rivo-nikos sudo[4080]: 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"