-- Logs begin at Thu 2025-11-13 01:44:55 +07, end at Mon 2025-12-22 19:12:00 +07. -- Dec 22 19:11:00 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:00 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:00 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:01 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:01 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:01 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:02 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:02 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:02 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:03 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:03 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:03 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:04 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:04 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:04 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:05 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:05 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:05 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:07 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:07 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:07 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:07 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:08 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:08 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:08 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:08 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:08 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:09 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:09 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:09 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:10 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:10 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:10 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:12 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:12 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:12 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:13 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:13 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:13 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:13 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:13 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:13 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:14 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:14 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:14 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:16 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:16 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:16 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:17 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:17 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:17 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:18 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 22 19:11:18 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:18 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:19 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 22 19:11:19 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:19 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:20 rivoplus volumio[3565]: verbose: New Socket.io Connection to 192.168.2.13:3000 from 192.168.2.12 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 22 19:11:20 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 19:11:20 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 19:11:20 rivoplus volumio[3565]: info: Discovery: Getting this device information Dec 22 19:11:20 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:20 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:20 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 19:11:20 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 22 19:11:20 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:20 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:21 rivoplus volumio[3565]: verbose: New Socket.io Connection to 192.168.2.13 from 192.168.2.12 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:21 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 19:11:21 rivoplus volumio[3565]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 22 19:11:21 rivoplus volumio[3565]: info: Received Get System Info Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 19:11:21 rivoplus volumio[3565]: info: Discovery: Getting this device information Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:21 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:21 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:21 rivoplus volumio[3565]: info: Listing playlists Dec 22 19:11:21 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 19:11:22 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 22 19:11:22 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:22 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:23 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 22 19:11:23 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:23 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:24 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 22 19:11:24 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:24 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:24 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetQueue Dec 22 19:11:24 rivoplus volumio[3565]: info: CoreStateMachine::getQueue Dec 22 19:11:24 rivoplus volumio[3565]: info: CorePlayQueue::getQueue Dec 22 19:11:25 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 22 19:11:25 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:25 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:26 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:26 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:26 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:27 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:27 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:27 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:28 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:28 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:28 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:29 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:29 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:29 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:30 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:30 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:30 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:31 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:31 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:31 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:32 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:32 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:32 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:33 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:33 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:33 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:33 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 22 19:11:33 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:34 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:34 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:34 rivoplus volumio[3565]: info: handleBrowseUri took 786 milliseconds Dec 22 19:11:34 rivoplus volumio[3565]: info: Preload queue cleared Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498233 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498234 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498235 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498236 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498237 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498238 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498239 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498240 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498241 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498242 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498243 Dec 22 19:11:34 rivoplus volumio[3565]: info: Preloading song: qobuz://song/498244 Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498233 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498234 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:34 rivoplus volumio[3565]: info: Executing endpoint getSimilarAlbums Dec 22 19:11:34 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498235 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498236 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:34 rivoplus volumio[3565]: info: Executing endpoint metavolumio Dec 22 19:11:34 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 19:11:34 rivoplus volumio[3565]: info: Executing endpoint metavolumio Dec 22 19:11:34 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498237 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498238 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498239 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:34 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498240 in service qobuz Dec 22 19:11:34 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:35 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498241 in service qobuz Dec 22 19:11:35 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:35 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498242 in service qobuz Dec 22 19:11:35 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:35 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498243 in service qobuz Dec 22 19:11:35 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:35 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:35 rivoplus volumio[3565]: info: Exploding uri qobuz://song/498244 in service qobuz Dec 22 19:11:35 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 669 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 607 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:35 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 858 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 702 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 868 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 788 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 866 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 1070 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 882 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 857 milliseconds Dec 22 19:11:35 rivoplus volumio[3565]: info: explodeUri took 899 milliseconds Dec 22 19:11:36 rivoplus volumio[3565]: info: explodeUri took 848 milliseconds Dec 22 19:11:36 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:36 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:36 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:37 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:37 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:37 rivoplus volumio[3565]: info: Preload queue cleared Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreStateMachine::ClearQueue Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreStateMachine::stop Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 19:11:37 rivoplus volumio[3565]: info: CorePlayQueue::clearPlayQueue Dec 22 19:11:37 rivoplus volumio[3565]: info: CorePlayQueue::saveQueue Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushQueue Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreStateMachine::addQueueItems Dec 22 19:11:37 rivoplus volumio[3565]: info: CorePlayQueue::addQueueItems Dec 22 19:11:37 rivoplus volumio[3565]: info: Preload queue cleared Dec 22 19:11:37 rivoplus volumio[3565]: info: Adding Item to queue: qobuz://album/0884977518269 Dec 22 19:11:37 rivoplus volumio[3565]: info: Exploding uri qobuz://album/0884977518269 in service qobuz Dec 22 19:11:37 rivoplus volumio[3565]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 22 19:11:37 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:37 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:37 rivoplus volumio[3565]: info: explodeUri took 415 milliseconds Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushQueue Dec 22 19:11:37 rivoplus volumio[3565]: info: CorePlayQueue::saveQueue Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreStateMachine::updateTrackBlock Dec 22 19:11:37 rivoplus volumio[3565]: info: CorePlayQueue::getTrackBlock Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPlay Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreStateMachine::play index 0 Dec 22 19:11:37 rivoplus volumio[3565]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 19:11:38 rivoplus volumio[3565]: info: CoreStateMachine::stop Dec 22 19:11:38 rivoplus volumio[3565]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 19:11:38 rivoplus volumio[3565]: info: CoreStateMachine::play index undefined Dec 22 19:11:38 rivoplus volumio[3565]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 19:11:38 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:38 rivoplus volumio[3565]: info: CoreStateMachine::startPlaybackTimer Dec 22 19:11:38 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:38 rivoplus volumio[3565]: info: [1766405498003] ControllerQobuz::clearAddPlayTrack Dec 22 19:11:38 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 19:11:38 rivoplus volumio[3565]: info: sendMpdCommand stop took 13 milliseconds Dec 22 19:11:38 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand clear Dec 22 19:11:38 rivoplus volumio[3565]: info: Dec 22 19:11:38 rivoplus volumio[3565]: ---------------------------- MPD announces system playlist update Dec 22 19:11:38 rivoplus volumio[3565]: info: Ignoring MPD Status Update Dec 22 19:11:38 rivoplus volumio[3565]: info: sendMpdCommand clear took 5 milliseconds Dec 22 19:11:38 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/498233" Dec 22 19:11:38 rivoplus volumio[3565]: info: Dec 22 19:11:38 rivoplus volumio[3565]: ---------------------------- MPD announces system playlist update Dec 22 19:11:38 rivoplus volumio[3565]: info: Ignoring MPD Status Update Dec 22 19:11:38 rivoplus volumio[3565]: info: Dec 22 19:11:38 rivoplus volumio[3565]: ---------------------------- MPD announces system playlist update Dec 22 19:11:38 rivoplus volumio[3565]: info: Ignoring MPD Status Update Dec 22 19:11:38 rivoplus volumio[3565]: error: updateQueue error: null Dec 22 19:11:38 rivoplus volumio[3565]: info: ------------------------------ 16ms Dec 22 19:11:38 rivoplus volumio[3565]: error: Upnp client error: Error: This socket has been ended by the other party Dec 22 19:11:38 rivoplus volumio[3565]: STREAMING PROXY: Handling url /?data=qobuz://song/498233 Dec 22 19:11:38 rivoplus volumio[3565]: info: Executing endpoint getStreamUrlqobuz Dec 22 19:11:38 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 22 19:11:38 rivoplus volumio[3565]: info: getStreamUrl took 467 milliseconds Dec 22 19:11:38 rivoplus volumio[3565]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3226296&eid=498233&fmt=6&profile=raw&app_id=539451548&cid=3582673&etsp=1766409098&hmac=vBSncCAn5Xdu3TFeEmEGgXZreRs Dec 22 19:11:39 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:39 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:39 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:40 rivoplus volumio[3565]: STREAMING PROXY: Response: 200, length: 18571956 Dec 22 19:11:40 rivoplus volumio[3565]: STREAMING PROXY: Client dropped request, destroying Dec 22 19:11:40 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/498233" Dec 22 19:11:40 rivoplus volumio[3565]: error: updateQueue error: null Dec 22 19:11:40 rivoplus volumio[3565]: error: updateQueue error: null Dec 22 19:11:40 rivoplus volumio[3565]: info: Dec 22 19:11:40 rivoplus volumio[3565]: ---------------------------- MPD announces system playlist update Dec 22 19:11:40 rivoplus volumio[3565]: info: Ignoring MPD Status Update Dec 22 19:11:40 rivoplus volumio[3565]: info: ------------------------------ 2159ms Dec 22 19:11:40 rivoplus volumio[3565]: info: ------------------------------ 2157ms Dec 22 19:11:40 rivoplus volumio[3565]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/498233" took 4 milliseconds Dec 22 19:11:40 rivoplus volumio[3565]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 22 19:11:40 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand play Dec 22 19:11:40 rivoplus volumio[3565]: info: Dec 22 19:11:40 rivoplus volumio[3565]: ---------------------------- MPD announces system playlist update Dec 22 19:11:40 rivoplus volumio[3565]: info: Ignoring MPD Status Update Dec 22 19:11:40 rivoplus volumio[3565]: info: Dec 22 19:11:40 rivoplus volumio[3565]: ---------------------------- MPD announces system playlist update Dec 22 19:11:40 rivoplus volumio[3565]: info: Ignoring MPD Status Update Dec 22 19:11:40 rivoplus volumio[3565]: info: ------------------------------ 17ms Dec 22 19:11:40 rivoplus volumio[3565]: info: sendMpdCommand play took 15 milliseconds Dec 22 19:11:40 rivoplus volumio[3565]: info: ------------------------------ 9ms Dec 22 19:11:40 rivoplus volumio[3565]: info: ------------------------------ 6ms Dec 22 19:11:40 rivoplus volumio[3565]: STREAMING PROXY: Handling url /?data=qobuz://song/498233 Dec 22 19:11:40 rivoplus volumio[3565]: info: Executing endpoint getStreamUrlqobuz Dec 22 19:11:40 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 22 19:11:40 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:40 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:40 rivoplus volumio[3565]: info: getStreamUrl took 652 milliseconds Dec 22 19:11:40 rivoplus volumio[3565]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3226296&eid=498233&fmt=6&profile=raw&app_id=539451548&cid=3582673&etsp=1766409100&hmac=M8bn3YLaYjScAjM_iK74UPnB8jU Dec 22 19:11:41 rivoplus volumio[3565]: STREAMING PROXY: Response: 200, length: 18571956 Dec 22 19:11:41 rivoplus volumio[3565]: info: Dec 22 19:11:41 rivoplus volumio[3565]: ---------------------------- MPD announces state update: player Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::getState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand status Dec 22 19:11:41 rivoplus volumio[3565]: info: Dec 22 19:11:41 rivoplus volumio[3565]: ---------------------------- MPD announces state update: player Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::getState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand status Dec 22 19:11:41 rivoplus volumio[3565]: info: Dec 22 19:11:41 rivoplus volumio[3565]: ---------------------------- MPD announces state update: player Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::getState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand status Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand status took 54 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 19:11:41 rivoplus volumio[3565]: info: Dec 22 19:11:41 rivoplus volumio[3565]: ---------------------------- MPD announces state update: player Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::getState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand status Dec 22 19:11:41 rivoplus volumio[3565]: info: Dec 22 19:11:41 rivoplus volumio[3565]: ---------------------------- MPD announces state update: player Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::getState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand status Dec 22 19:11:41 rivoplus volumio[3565]: info: Dec 22 19:11:41 rivoplus volumio[3565]: ---------------------------- MPD announces state update: player Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::getState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand status Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand status took 68 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand status took 65 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand status took 8 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseTrackInfo Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::servicePushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:41 rivoplus volumio[3565]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"498233","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/498233","trackType":"qobuz"} Dec 22 19:11:41 rivoplus volumio[3565]: verbose: CURRENT POSITION 0 Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState stateService play Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState currentStatus stop Dec 22 19:11:41 rivoplus volumio[3565]: info: ------------------------------ 99ms Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand status took 33 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand status took 30 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseState Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseTrackInfo Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseTrackInfo Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::servicePushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:41 rivoplus volumio[3565]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"498233","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/498233","trackType":"qobuz"} Dec 22 19:11:41 rivoplus volumio[3565]: verbose: CURRENT POSITION 0 Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState stateService play Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState currentStatus play Dec 22 19:11:41 rivoplus volumio[3565]: info: Received an update from plugin. extracting info from payload Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::servicePushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:41 rivoplus volumio[3565]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"498233","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/498233","trackType":"qobuz"} Dec 22 19:11:41 rivoplus volumio[3565]: verbose: CURRENT POSITION 0 Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState stateService play Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState currentStatus play Dec 22 19:11:41 rivoplus volumio[3565]: info: Received an update from plugin. extracting info from payload Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::servicePushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:41 rivoplus volumio[3565]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"498233","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/498233","trackType":"qobuz"} Dec 22 19:11:41 rivoplus volumio[3565]: verbose: CURRENT POSITION 0 Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState stateService play Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState currentStatus play Dec 22 19:11:41 rivoplus volumio[3565]: info: Received an update from plugin. extracting info from payload Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: ------------------------------ 200ms Dec 22 19:11:41 rivoplus volumio[3565]: info: ------------------------------ 200ms Dec 22 19:11:41 rivoplus volumio[3565]: info: ------------------------------ 143ms Dec 22 19:11:41 rivoplus volumio[3565]: info: sendMpdCommand playlistinfo took 104 milliseconds Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseTrackInfo Dec 22 19:11:41 rivoplus volumio[3565]: verbose: ControllerMpd::parseTrackInfo Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::servicePushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:41 rivoplus volumio[3565]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"498233","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/498233","trackType":"qobuz"} Dec 22 19:11:41 rivoplus volumio[3565]: verbose: CURRENT POSITION 0 Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState stateService play Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState currentStatus play Dec 22 19:11:41 rivoplus volumio[3565]: info: Received an update from plugin. extracting info from payload Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: ControllerMpd::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::servicePushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CorePlayQueue::getTrack 0 Dec 22 19:11:41 rivoplus volumio[3565]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"724 Kbps","isStreaming":false,"title":"498233","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/498233","trackType":"qobuz"} Dec 22 19:11:41 rivoplus volumio[3565]: verbose: CURRENT POSITION 0 Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState stateService play Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::syncState currentStatus play Dec 22 19:11:41 rivoplus volumio[3565]: info: Received an update from plugin. extracting info from payload Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreStateMachine::pushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioPushState Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output update for this device Dec 22 19:11:41 rivoplus volumio[3565]: info: MRS: Pushing multiroomSync output Dec 22 19:11:41 rivoplus volumio[3565]: info: ------------------------------ 196ms Dec 22 19:11:41 rivoplus volumio[3565]: info: ------------------------------ 195ms Dec 22 19:11:41 rivoplus volumio[3565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 19:11:41 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetState Dec 22 19:11:41 rivoplus volumio[3565]: info: Exiting sleep status due to volume change Dec 22 19:11:41 rivoplus volumio[3565]: info: Signalling Playback active due to playback status change Dec 22 19:11:41 rivoplus volumio[3565]: info: MCU Signalled Playback Active Dec 22 19:11:41 rivoplus volumio[3565]: info: MCU Signalled Sleep Mode Disabled Dec 22 19:11:41 rivoplus volumio[3565]: info: MCU Signalled Sleep Mode Disabled Dec 22 19:11:41 rivoplus sudo[8428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Dec 22 19:11:41 rivoplus sudo[8428]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 19:11:41 rivoplus sudo[8430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Dec 22 19:11:41 rivoplus sudo[8430]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 19:11:42 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Dec 22 19:11:42 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Dec 22 19:11:42 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Dec 22 19:11:42 rivoplus sudo[8428]: pam_unix(sudo:session): session closed for user root Dec 22 19:11:42 rivoplus sudo[8430]: pam_unix(sudo:session): session closed for user root Dec 22 19:11:42 rivoplus sudo[8436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Dec 22 19:11:42 rivoplus sudo[8436]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 19:11:42 rivoplus sudo[8436]: pam_unix(sudo:session): session closed for user root Dec 22 19:11:42 rivoplus sudo[8434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Dec 22 19:11:42 rivoplus sudo[8434]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 19:11:42 rivoplus sudo[8434]: pam_unix(sudo:session): session closed for user root Dec 22 19:11:42 rivoplus volumio[3565]: STREAMING PROXY: Client dropped request, destroying Dec 22 19:11:44 rivoplus volumio[3565]: info: Executing endpoint metavolumio Dec 22 19:11:44 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 19:11:44 rivoplus volumio[3565]: info: Executing endpoint metavolumio Dec 22 19:11:44 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 19:11:44 rivoplus volumio[3565]: info: Executing endpoint metavolumio Dec 22 19:11:44 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 19:11:51 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Dec 22 19:11:51 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 19:11:51 rivoplus volumio[3565]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 19:11:51 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 22 19:11:51 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 22 19:11:52 rivoplus volumio[3565]: info: searchQOBUZUri took 1199 milliseconds Dec 22 19:11:52 rivoplus volumio[3565]: info: All search sources collected, pushing search results Dec 22 19:11:52 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 22 19:11:53 rivoplus volumio[3565]: info: handleBrowseUri took 1331 milliseconds Dec 22 19:11:54 rivoplus volumio[3565]: info: Preload queue cleared Dec 22 19:11:54 rivoplus volumio[3565]: info: Executing endpoint getSimilarArtists Dec 22 19:11:54 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 22 19:11:54 rivoplus volumio[3565]: info: Executing endpoint metavolumio Dec 22 19:11:54 rivoplus volumio[3565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 19:11:59 rivoplus volumio[3565]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 19:11:59 rivoplus volumio[3565]: TypeError: Cannot read property 'slice' of null Dec 22 19:11:59 rivoplus volumio[3565]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 22 19:11:59 rivoplus volumio[3565]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 22 19:11:59 rivoplus volumio[3565]: at Object.onceWrapper (events.js:520:26) Dec 22 19:11:59 rivoplus volumio[3565]: at ClientRequest.emit (events.js:400:28) Dec 22 19:11:59 rivoplus volumio[3565]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Dec 22 19:11:59 rivoplus volumio[3565]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Dec 22 19:11:59 rivoplus volumio[3565]: at TLSSocket.socketOnData (_http_client.js:515:22) Dec 22 19:11:59 rivoplus volumio[3565]: at TLSSocket.emit (events.js:400:28) Dec 22 19:11:59 rivoplus volumio[3565]: at addChunk (internal/streams/readable.js:293:12) Dec 22 19:11:59 rivoplus volumio[3565]: at readableAddChunk (internal/streams/readable.js:267:9) Dec 22 19:11:59 rivoplus volumio[3565]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Dec 22 19:11:59 rivoplus volumio[3565]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 22 19:11:59 rivoplus volumio[3565]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 19:12:00 rivoplus sudo[8520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 19:11 Dec 22 19:12:00 rivoplus sudo[8520]: 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"