-- Logs begin at Thu 2025-12-11 09:16:34 CET, end at Thu 2025-12-11 09:42:03 CET. -- Dec 11 09:41:00 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 09:41:00 rivoplus volumio[3284]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 11 09:41:00 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 11 09:41:00 rivoplus volumio[3284]: info: Received Get System Version Dec 11 09:41:00 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 09:41:00 rivoplus volumio[3284]: info: Received Get System Info Dec 11 09:41:00 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 09:41:00 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 09:41:00 rivoplus volumio[3284]: info: Discovery: Getting this device information Dec 11 09:41:00 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:00 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 09:41:05 rivoplus volumio[3284]: Searching plugin music_service/qobuz Dec 11 09:41:05 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 11 09:41:06 rivoplus volumio[3284]: info: searchQOBUZUri took 1015 milliseconds Dec 11 09:41:06 rivoplus volumio[3284]: info: All search sources collected, pushing search results Dec 11 09:41:07 rivoplus volumio[3284]: Searching plugin music_service/qobuz Dec 11 09:41:07 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 11 09:41:07 rivoplus volumio[3284]: Searching plugin music_service/qobuz Dec 11 09:41:07 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 11 09:41:07 rivoplus volumio[3284]: info: searchQOBUZUri took 833 milliseconds Dec 11 09:41:07 rivoplus volumio[3284]: info: All search sources collected, pushing search results Dec 11 09:41:08 rivoplus volumio[3284]: info: searchQOBUZUri took 616 milliseconds Dec 11 09:41:08 rivoplus volumio[3284]: info: All search sources collected, pushing search results Dec 11 09:41:08 rivoplus volumio[3284]: verbose: New Socket.io Connection to 192.168.1.106 from 192.168.1.110 UA: Mozilla/5.0 (iPad; CPU OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 09:41:08 rivoplus volumio[3284]: Searching plugin music_service/qobuz Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 09:41:08 rivoplus volumio[3284]: info: Received Get System Info Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 09:41:08 rivoplus volumio[3284]: info: Discovery: Getting this device information Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:08 rivoplus volumio[3284]: info: Listing playlists Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetQueue Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreStateMachine::getQueue Dec 11 09:41:08 rivoplus volumio[3284]: info: CorePlayQueue::getQueue Dec 11 09:41:08 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 09:41:09 rivoplus volumio[3284]: info: searchQOBUZUri took 865 milliseconds Dec 11 09:41:09 rivoplus volumio[3284]: info: All search sources collected, pushing search results Dec 11 09:41:14 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 11 09:41:14 rivoplus volumio[3284]: info: handleBrowseUri took 272 milliseconds Dec 11 09:41:14 rivoplus volumio[3284]: info: Preload queue cleared Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036865 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036866 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036867 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036868 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036869 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036870 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036871 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036872 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036873 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036874 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036875 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036876 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036877 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036878 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036879 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036880 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036881 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036882 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036883 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036884 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036885 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036886 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036887 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036888 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036889 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036890 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036891 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036892 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036893 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036894 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036895 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036896 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036897 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036898 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036899 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036900 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036901 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036902 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036903 Dec 11 09:41:14 rivoplus volumio[3284]: info: Preloading song: qobuz://song/232036904 Dec 11 09:41:14 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036865 in service qobuz Dec 11 09:41:14 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:14 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036866 in service qobuz Dec 11 09:41:14 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:14 rivoplus volumio[3284]: info: No valid Plugin REST Endpoint: getSimilarAlbums Dec 11 09:41:14 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036867 in service qobuz Dec 11 09:41:14 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:14 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036868 in service qobuz Dec 11 09:41:14 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036869 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 189 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 244 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036870 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 171 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036871 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036872 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 159 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 233 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036873 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 170 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036874 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036875 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 178 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036876 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 281 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036877 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 210 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 192 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036878 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 195 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036879 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036880 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 193 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036881 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 297 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 206 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036882 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 187 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036883 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 198 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036884 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036885 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 203 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 175 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036886 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 175 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036887 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 176 milliseconds Dec 11 09:41:15 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036888 in service qobuz Dec 11 09:41:15 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:15 rivoplus volumio[3284]: info: explodeUri took 159 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036889 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 180 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036890 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 199 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036891 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 167 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036892 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036893 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 213 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 171 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036894 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 199 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036895 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036896 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036897 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 211 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 272 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 167 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036898 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036899 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 179 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 229 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036900 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036901 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 217 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 173 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036902 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 199 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036903 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 165 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: Exploding uri qobuz://song/232036904 in service qobuz Dec 11 09:41:16 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 215 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 181 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 192 milliseconds Dec 11 09:41:16 rivoplus volumio[3284]: info: explodeUri took 210 milliseconds Dec 11 09:41:28 rivoplus volumio[3284]: info: Preload queue cleared Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::ClearQueue Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::stPlaybackTimer Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::updateTrackBlock Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::getTrackBlock Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 12 Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 12 Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::serviceStop Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 12 Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::serviceStop Dec 11 09:41:28 rivoplus volumio[3284]: info: [1765442488473] ControllerQobuz::stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 09:41:28 rivoplus volumio[3284]: info: ControllerMpd::stop Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::clearPlayQueue Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::saveQueue Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::addQueueItems Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::addQueueItems Dec 11 09:41:28 rivoplus volumio[3284]: info: Preload queue cleared Dec 11 09:41:28 rivoplus volumio[3284]: info: Adding Item to queue: qobuz://artist/20687559 Dec 11 09:41:28 rivoplus volumio[3284]: info: Exploding uri qobuz://artist/20687559 in service qobuz Dec 11 09:41:28 rivoplus volumio[3284]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Dec 11 09:41:28 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:28 rivoplus volumio[3284]: info: sendMpdCommand stop took 57 milliseconds Dec 11 09:41:28 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:28 rivoplus volumio[3284]: info: Dec 11 09:41:28 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:28 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:28 rivoplus volumio[3284]: info: Dec 11 09:41:28 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:28 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:28 rivoplus volumio[3284]: info: sendMpdCommand status took 7 milliseconds Dec 11 09:41:28 rivoplus volumio[3284]: info: sendMpdCommand status took 5 milliseconds Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:28 rivoplus volumio[3284]: info: sendMpdCommand status took 7 milliseconds Dec 11 09:41:28 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:28 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 12 Dec 11 09:41:28 rivoplus volumio[3284]: 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":"760503","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/760503","trackType":"qobuz"} Dec 11 09:41:28 rivoplus volumio[3284]: verbose: CURRENT POSITION 12 Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: No code Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 12 Dec 11 09:41:28 rivoplus volumio[3284]: 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":"760503","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/760503","trackType":"qobuz"} Dec 11 09:41:28 rivoplus volumio[3284]: verbose: CURRENT POSITION 12 Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: No code Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: ------------------------------ 72ms Dec 11 09:41:28 rivoplus volumio[3284]: info: ------------------------------ 72ms Dec 11 09:41:28 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 65 milliseconds Dec 11 09:41:28 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:28 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 12 Dec 11 09:41:28 rivoplus volumio[3284]: 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":"760503","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/760503","trackType":"qobuz"} Dec 11 09:41:28 rivoplus volumio[3284]: verbose: CURRENT POSITION 12 Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus stop Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: No code Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:28 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:28 rivoplus volumio[3284]: info: ------------------------------ 123ms Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:28 rivoplus volumio[3284]: info: MCU Signalled Playback Inactive Dec 11 09:41:28 rivoplus volumio[3284]: info: MCU Signalled Playback Active Dec 11 09:41:29 rivoplus volumio[3284]: info: explodeUri took 626 milliseconds Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushQueue Dec 11 09:41:29 rivoplus volumio[3284]: info: CorePlayQueue::saveQueue Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::updateTrackBlock Dec 11 09:41:29 rivoplus volumio[3284]: info: CorePlayQueue::getTrackBlock Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPlay Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::play index 0 Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::stop Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::play index undefined Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 09:41:29 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::startPlaybackTimer Dec 11 09:41:29 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:29 rivoplus volumio[3284]: info: [1765442489133] ControllerQobuz::clearAddPlayTrack Dec 11 09:41:29 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 09:41:29 rivoplus volumio[3284]: info: sendMpdCommand stop took 8 milliseconds Dec 11 09:41:29 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 09:41:29 rivoplus volumio[3284]: info: Dec 11 09:41:29 rivoplus volumio[3284]: ---------------------------- MPD announces system playlist update Dec 11 09:41:29 rivoplus volumio[3284]: info: Ignoring MPD Status Update Dec 11 09:41:29 rivoplus volumio[3284]: info: sendMpdCommand clear took 2 milliseconds Dec 11 09:41:29 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/290579550" Dec 11 09:41:29 rivoplus volumio[3284]: info: Dec 11 09:41:29 rivoplus volumio[3284]: ---------------------------- MPD announces system playlist update Dec 11 09:41:29 rivoplus volumio[3284]: info: Ignoring MPD Status Update Dec 11 09:41:29 rivoplus volumio[3284]: info: Dec 11 09:41:29 rivoplus volumio[3284]: ---------------------------- MPD announces system playlist update Dec 11 09:41:29 rivoplus volumio[3284]: info: Ignoring MPD Status Update Dec 11 09:41:29 rivoplus volumio[3284]: STREAMING PROXY: Handling url /?data=qobuz://song/290579550 Dec 11 09:41:29 rivoplus volumio[3284]: error: updateQueue error: null Dec 11 09:41:29 rivoplus volumio[3284]: info: ------------------------------ 6ms Dec 11 09:41:29 rivoplus volumio[3284]: info: Executing endpoint getStreamUrlqobuz Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 11 09:41:29 rivoplus volumio[3284]: info: getStreamUrl took 139 milliseconds Dec 11 09:41:29 rivoplus volumio[3284]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=8185027&eid=290579550&fmt=5&profile=raw&app_id=539451548&cid=3405871&etsp=1765446089&hmac=j8ADKZ_OAcRfYojzWrB33L2dk3A Dec 11 09:41:29 rivoplus volumio[3284]: STREAMING PROXY: Response: 200, length: 7314284 Dec 11 09:41:29 rivoplus volumio[3284]: STREAMING PROXY: Client dropped request, destroying Dec 11 09:41:29 rivoplus volumio[3284]: error: updateQueue error: null Dec 11 09:41:29 rivoplus volumio[3284]: error: updateQueue error: null Dec 11 09:41:29 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/290579550" Dec 11 09:41:29 rivoplus volumio[3284]: info: ------------------------------ 815ms Dec 11 09:41:29 rivoplus volumio[3284]: info: ------------------------------ 814ms Dec 11 09:41:29 rivoplus volumio[3284]: info: Dec 11 09:41:29 rivoplus volumio[3284]: ---------------------------- MPD announces system playlist update Dec 11 09:41:29 rivoplus volumio[3284]: info: Ignoring MPD Status Update Dec 11 09:41:29 rivoplus volumio[3284]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/290579550" took 7 milliseconds Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 09:41:29 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand play Dec 11 09:41:29 rivoplus volumio[3284]: info: Dec 11 09:41:29 rivoplus volumio[3284]: ---------------------------- MPD announces system playlist update Dec 11 09:41:29 rivoplus volumio[3284]: info: Ignoring MPD Status Update Dec 11 09:41:29 rivoplus volumio[3284]: info: Dec 11 09:41:29 rivoplus volumio[3284]: ---------------------------- MPD announces system playlist update Dec 11 09:41:29 rivoplus volumio[3284]: STREAMING PROXY: Handling url /?data=qobuz://song/290579550 Dec 11 09:41:29 rivoplus volumio[3284]: info: Ignoring MPD Status Update Dec 11 09:41:29 rivoplus volumio[3284]: info: ------------------------------ 12ms Dec 11 09:41:29 rivoplus volumio[3284]: info: sendMpdCommand play took 6 milliseconds Dec 11 09:41:29 rivoplus volumio[3284]: info: ------------------------------ 6ms Dec 11 09:41:29 rivoplus volumio[3284]: info: ------------------------------ 4ms Dec 11 09:41:29 rivoplus volumio[3284]: info: Executing endpoint getStreamUrlqobuz Dec 11 09:41:29 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 11 09:41:30 rivoplus volumio[3284]: info: getStreamUrl took 144 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=8185027&eid=290579550&fmt=5&profile=raw&app_id=539451548&cid=3405871&etsp=1765446090&hmac=bVoFMQQtPzj9QkM6CNliTSxTz70 Dec 11 09:41:30 rivoplus volumio[3284]: STREAMING PROXY: Response: 200, length: 7314284 Dec 11 09:41:30 rivoplus volumio[3284]: info: Dec 11 09:41:30 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:30 rivoplus volumio[3284]: info: Dec 11 09:41:30 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:30 rivoplus volumio[3284]: info: Dec 11 09:41:30 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand status took 26 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:30 rivoplus volumio[3284]: info: Dec 11 09:41:30 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand status took 28 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand status took 27 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:30 rivoplus volumio[3284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"290579550","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/290579550","trackType":"qobuz"} Dec 11 09:41:30 rivoplus volumio[3284]: verbose: CURRENT POSITION 0 Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService play Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus stop Dec 11 09:41:30 rivoplus volumio[3284]: info: ------------------------------ 38ms Dec 11 09:41:30 rivoplus volumio[3284]: info: Dec 11 09:41:30 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:30 rivoplus volumio[3284]: info: Dec 11 09:41:30 rivoplus volumio[3284]: ---------------------------- MPD announces state update: player Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::getState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand status Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand status took 10 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand status took 5 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand status took 3 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseState Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:30 rivoplus volumio[3284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":617,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"290579550","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/290579550","trackType":"qobuz"} Dec 11 09:41:30 rivoplus volumio[3284]: verbose: CURRENT POSITION 0 Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService play Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:41:30 rivoplus volumio[3284]: info: Received an update from plugin. extracting info from payload Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:30 rivoplus volumio[3284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"290579550","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/290579550","trackType":"qobuz"} Dec 11 09:41:30 rivoplus volumio[3284]: verbose: CURRENT POSITION 0 Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService play Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:41:30 rivoplus volumio[3284]: info: Received an update from plugin. extracting info from payload Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: ------------------------------ 98ms Dec 11 09:41:30 rivoplus volumio[3284]: info: ------------------------------ 97ms Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:30 rivoplus volumio[3284]: verbose: ControllerMpd::parseTrackInfo Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:30 rivoplus volumio[3284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"290579550","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/290579550","trackType":"qobuz"} Dec 11 09:41:30 rivoplus volumio[3284]: verbose: CURRENT POSITION 0 Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService play Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:41:30 rivoplus volumio[3284]: info: Received an update from plugin. extracting info from payload Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:30 rivoplus volumio[3284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"290579550","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/290579550","trackType":"qobuz"} Dec 11 09:41:30 rivoplus volumio[3284]: verbose: CURRENT POSITION 0 Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService play Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:41:30 rivoplus volumio[3284]: info: Received an update from plugin. extracting info from payload Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: ControllerMpd::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::servicePushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CorePlayQueue::getTrack 0 Dec 11 09:41:30 rivoplus volumio[3284]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"290579550","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/290579550","trackType":"qobuz"} Dec 11 09:41:30 rivoplus volumio[3284]: verbose: CURRENT POSITION 0 Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState stateService play Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::syncState currentStatus play Dec 11 09:41:30 rivoplus volumio[3284]: info: Received an update from plugin. extracting info from payload Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreStateMachine::pushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioPushState Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:41:30 rivoplus volumio[3284]: info: ------------------------------ 195ms Dec 11 09:41:30 rivoplus volumio[3284]: info: ------------------------------ 192ms Dec 11 09:41:30 rivoplus volumio[3284]: info: ------------------------------ 193ms Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Signalling Playback active due to playback status change Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: Updating RAAT Signal Path Dec 11 09:41:30 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 11 09:41:30 rivoplus volumio[3284]: STREAMING PROXY: Client dropped request, destroying Dec 11 09:41:30 rivoplus volumio[3284]: info: handleBrowseUri took 186 milliseconds Dec 11 09:41:30 rivoplus volumio[3284]: info: Preload queue cleared Dec 11 09:41:30 rivoplus volumio[3284]: info: No valid Plugin REST Endpoint: getSimilarArtists Dec 11 09:41:34 rivoplus volumio[3284]: info: CoreCommandRouter::volumioGetState Dec 11 09:42:03 rivoplus volumio[3284]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Dec 11 09:42:03 rivoplus volumio[3284]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 09:42:03 rivoplus volumio[3284]: TypeError: Cannot read property 'then' of undefined Dec 11 09:42:03 rivoplus volumio[3284]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Dec 11 09:42:03 rivoplus volumio[3284]: at Socket.emit (events.js:400:28) Dec 11 09:42:03 rivoplus volumio[3284]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 11 09:42:03 rivoplus volumio[3284]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 11 09:42:03 rivoplus volumio[3284]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 09:42:03 rivoplus sudo[11323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 09:41 Dec 11 09:42:03 rivoplus sudo[11323]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:44:55 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="af8df74278231e4f9fb9f04db56c320f"