-- Logs begin at Sat 2025-07-05 16:07:59 CEST, end at Sun 2025-07-06 08:05:28 CEST. -- Jul 06 08:04:05 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 06 08:04:08 rivo volumio[3199]: info: Preload queue cleared Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3131 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3132 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2967 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2964 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2966 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2965 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3769 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3772 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3784 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3774 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3647 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3240 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3340 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3341 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3342 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3343 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3344 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3345 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3346 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3347 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3348 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3349 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3350 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3092 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3086 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3087 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3088 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3089 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3090 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3091 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3480 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2698 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2963 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3378 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3390 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3391 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3392 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3393 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3394 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3395 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3376 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3397 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3377 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3398 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2982 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3755 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3765 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3748 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3758 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3762 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3756 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3744 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3759 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3764 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3742 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3761 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3750 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3766 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3741 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3752 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3747 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3229 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3230 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3231 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3232 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3233 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3234 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3235 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3236 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3237 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3238 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3469 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3473 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3355 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2700 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2695 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2690 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3080 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3016 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3242 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2809 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3227 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2754 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2753 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2755 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2745 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2751 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2750 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2752 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2759 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2749 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2758 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2760 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2748 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2757 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2747 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2756 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2746 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2736 Jul 06 08:04:08 rivo volumio[3199]: info: Preloading song: upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2278 Jul 06 08:04:08 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3131 in service upnp_browser Jul 06 08:04:08 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3132 in service upnp_browser Jul 06 08:04:08 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2967 in service upnp_browser Jul 06 08:04:08 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2964 in service upnp_browser Jul 06 08:04:08 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2966 in service upnp_browser Jul 06 08:04:08 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2965 in service upnp_browser Jul 06 08:04:08 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3769 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3772 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3784 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3774 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3647 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3240 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3340 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3341 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3342 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3343 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3344 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3345 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3346 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3347 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3348 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3349 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 08:04:09 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 08:04:09 rivo volumio[3199]: info: Discovery: Getting this device information Jul 06 08:04:09 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:04:09 rivo volumio[3199]: info: CorePlayQueue::getTrack 8 Jul 06 08:04:09 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3350 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3092 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3086 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3087 in service upnp_browser Jul 06 08:04:09 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3088 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3089 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3090 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3091 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3480 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2698 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2963 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3378 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3390 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3391 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3392 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3393 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3394 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3395 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3376 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3397 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3377 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3398 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2982 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3755 in service upnp_browser Jul 06 08:04:10 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3765 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3748 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3758 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3762 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3756 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3744 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3759 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3764 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3742 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3761 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3750 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3766 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3741 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3752 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3747 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3229 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3230 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3231 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3232 in service upnp_browser Jul 06 08:04:11 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3233 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3234 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3235 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3236 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3237 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3238 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3469 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3473 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3355 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2700 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2695 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2690 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3080 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3016 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3242 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2809 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@3227 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2754 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2753 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2755 in service upnp_browser Jul 06 08:04:12 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2745 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2751 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2750 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2752 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2759 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2749 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2758 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2760 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2748 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2757 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2747 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2756 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2746 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2736 in service upnp_browser Jul 06 08:04:13 rivo volumio[3199]: info: Exploding uri upnp/http://192.168.1.20:50001/ContentDirectory/control@23$@2278 in service upnp_browser Jul 06 08:04:39 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 08:04:39 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 08:04:39 rivo volumio[3199]: info: Discovery: Getting this device information Jul 06 08:04:39 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:04:39 rivo volumio[3199]: info: CorePlayQueue::getTrack 8 Jul 06 08:04:39 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 08:04:43 rivo volumio[3199]: info: CoreCommandRouter::volumioAddQueueItems Jul 06 08:04:43 rivo volumio[3199]: info: CoreStateMachine::addQueueItems Jul 06 08:04:43 rivo volumio[3199]: info: CorePlayQueue::addQueueItems Jul 06 08:04:43 rivo volumio[3199]: info: Preload queue cleared Jul 06 08:04:43 rivo volumio[3199]: info: Adding Item to queue: upnp/folder/http://192.168.1.20:50001/ContentDirectory/control@23 Jul 06 08:04:43 rivo volumio[3199]: info: Exploding uri upnp/folder/http://192.168.1.20:50001/ContentDirectory/control@23 in service upnp_browser Jul 06 08:04:46 rivo volumio[3199]: info: CoreCommandRouter::volumioPushQueue Jul 06 08:04:46 rivo volumio[3199]: info: CorePlayQueue::saveQueue Jul 06 08:04:46 rivo volumio[3199]: info: CoreStateMachine::updateTrackBlock Jul 06 08:04:46 rivo volumio[3199]: info: CorePlayQueue::getTrackBlock Jul 06 08:04:46 rivo volumio[3199]: info: CorePlayQueue::getTrack 8 Jul 06 08:04:46 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:46 rivo volumio[3199]: info: Prefetching next song Jul 06 08:04:46 rivo volumio[3199]: info: [1751781886442] ControllerQobuz::prefetch Jul 06 08:04:46 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/7222040" Jul 06 08:04:46 rivo volumio[3199]: STREAMING PROXY: Handling url /?data=qobuz://song/7222040 Jul 06 08:04:46 rivo volumio[3199]: info: Executing endpoint getStreamUrlqobuz Jul 06 08:04:46 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 06 08:04:47 rivo volumio[3199]: info: getStreamUrl took 440 milliseconds Jul 06 08:04:47 rivo volumio[3199]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=69368&eid=7222040&fmt=6&profile=raw&app_id=539451548&cid=1486945&etsp=1751785486&hmac=-trGtPp9LqI4VwS52qIjnpLcSpE Jul 06 08:04:47 rivo volumio[3199]: STREAMING PROXY: Response: 200, length: 17721575 Jul 06 08:04:47 rivo volumio[3199]: STREAMING PROXY: Client dropped request, destroying Jul 06 08:04:47 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/7222040" Jul 06 08:04:47 rivo volumio[3199]: info: Jul 06 08:04:47 rivo volumio[3199]: ---------------------------- MPD announces system playlist update Jul 06 08:04:47 rivo volumio[3199]: STREAMING PROXY: Handling url /?data=qobuz://song/7222040 Jul 06 08:04:47 rivo volumio[3199]: info: Ignoring MPD Status Update Jul 06 08:04:47 rivo volumio[3199]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/7222040" took 6 milliseconds Jul 06 08:04:47 rivo volumio[3199]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 08:04:47 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 06 08:04:47 rivo volumio[3199]: info: ------------------------------ 11ms Jul 06 08:04:47 rivo volumio[3199]: info: sendMpdCommand consume 1 took 5 milliseconds Jul 06 08:04:47 rivo volumio[3199]: info: Executing endpoint getStreamUrlqobuz Jul 06 08:04:47 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 06 08:04:47 rivo volumio[3199]: info: getStreamUrl took 351 milliseconds Jul 06 08:04:47 rivo volumio[3199]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=69368&eid=7222040&fmt=6&profile=raw&app_id=539451548&cid=1486945&etsp=1751785487&hmac=hRCO9yW56DD_6ME6uUokH0XRSMA Jul 06 08:04:47 rivo volumio[3199]: STREAMING PROXY: Response: 200, length: 17721575 Jul 06 08:04:48 rivo volumio[3199]: STREAMING PROXY: Client dropped request, destroying Jul 06 08:04:48 rivo volumio[3199]: info: Jul 06 08:04:48 rivo volumio[3199]: ---------------------------- MPD announces system playlist update Jul 06 08:04:48 rivo volumio[3199]: info: Ignoring MPD Status Update Jul 06 08:04:48 rivo volumio[3199]: info: Jul 06 08:04:48 rivo volumio[3199]: ---------------------------- MPD announces state update: player Jul 06 08:04:48 rivo volumio[3199]: info: ControllerMpd::getState Jul 06 08:04:48 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand status Jul 06 08:04:48 rivo volumio[3199]: info: ------------------------------ 5ms Jul 06 08:04:48 rivo volumio[3199]: info: CoreStateMachine::startPlaybackTimer Jul 06 08:04:48 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:04:49 rivo volumio[3199]: info: Jul 06 08:04:49 rivo volumio[3199]: ---------------------------- MPD announces state update: player Jul 06 08:04:49 rivo volumio[3199]: info: sendMpdCommand status took 1229 milliseconds Jul 06 08:04:49 rivo volumio[3199]: info: ControllerMpd::getState Jul 06 08:04:49 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand status Jul 06 08:04:49 rivo volumio[3199]: verbose: ControllerMpd::parseState Jul 06 08:04:49 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 08:04:49 rivo volumio[3199]: info: sendMpdCommand status took 5 milliseconds Jul 06 08:04:49 rivo volumio[3199]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 06 08:04:49 rivo volumio[3199]: verbose: ControllerMpd::parseState Jul 06 08:04:49 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 08:04:49 rivo volumio[3199]: verbose: ControllerMpd::parseTrackInfo Jul 06 08:04:49 rivo volumio[3199]: info: ControllerMpd::pushState Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::servicePushState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7222040","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/7222040","trackType":"qobuz"} Jul 06 08:04:49 rivo volumio[3199]: verbose: CURRENT POSITION 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::syncState stateService play Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::syncState currentStatus play Jul 06 08:04:49 rivo volumio[3199]: info: Received an update from plugin. extracting info from payload Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:04:49 rivo volumio[3199]: info: ------------------------------ 1296ms Jul 06 08:04:49 rivo volumio[3199]: info: sendMpdCommand playlistinfo took 57 milliseconds Jul 06 08:04:49 rivo volumio[3199]: verbose: ControllerMpd::parseTrackInfo Jul 06 08:04:49 rivo volumio[3199]: info: ControllerMpd::pushState Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::servicePushState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"7222040","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/7222040","trackType":"qobuz"} Jul 06 08:04:49 rivo volumio[3199]: verbose: CURRENT POSITION 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::syncState stateService play Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::syncState currentStatus play Jul 06 08:04:49 rivo volumio[3199]: info: Received an update from plugin. extracting info from payload Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:04:49 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:04:49 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:04:49 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:04:49 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:04:49 rivo volumio[3199]: info: ------------------------------ 116ms Jul 06 08:04:51 rivo volumio[3199]: info: Executing endpoint metavolumio Jul 06 08:04:51 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 06 08:04:51 rivo volumio[3199]: info: Executing endpoint metavolumio Jul 06 08:04:51 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 06 08:04:51 rivo volumio[3199]: info: Executing endpoint metavolumio Jul 06 08:04:51 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 06 08:04:53 rivo volumio[3199]: info: CoreCommandRouter::volumioplayNextItems Jul 06 08:04:53 rivo volumio[3199]: info: CoreStateMachine::playNextItems Jul 06 08:04:53 rivo volumio[3199]: info: CorePlayQueue::addPlayNextItems Jul 06 08:04:53 rivo volumio[3199]: info: Preload queue cleared Jul 06 08:04:53 rivo volumio[3199]: info: Adding Item to play next: upnp/folder/http://192.168.1.20:50001/ContentDirectory/control@23 Jul 06 08:04:53 rivo volumio[3199]: info: Using cached record of: upnp/folder/http://192.168.1.20:50001/ContentDirectory/control@23 Jul 06 08:04:53 rivo volumio[3199]: info: CoreCommandRouter::volumioPushQueue Jul 06 08:04:53 rivo volumio[3199]: info: CorePlayQueue::saveQueue Jul 06 08:04:53 rivo volumio[3199]: info: CoreStateMachine::updateTrackBlock Jul 06 08:04:53 rivo volumio[3199]: info: CorePlayQueue::getTrackBlock Jul 06 08:05:04 rivo volumio[3199]: info: Preload queue cleared Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::ClearQueue Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::stop Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::stPlaybackTimer Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::updateTrackBlock Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::getTrackBlock Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:05:04 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:05:04 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::serviceStop Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::getTrack 9 Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::serviceStop Jul 06 08:05:04 rivo volumio[3199]: info: [1751781904212] ControllerQobuz::stop Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 08:05:04 rivo volumio[3199]: info: ControllerMpd::stop Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::clearPlayQueue Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::saveQueue Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::volumioPushQueue Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::addQueueItems Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::addQueueItems Jul 06 08:05:04 rivo volumio[3199]: info: Preload queue cleared Jul 06 08:05:04 rivo volumio[3199]: info: Adding Item to queue: upnp/folder/http://192.168.1.20:50001/ContentDirectory/control@23 Jul 06 08:05:04 rivo volumio[3199]: info: Using cached record of: upnp/folder/http://192.168.1.20:50001/ContentDirectory/control@23 Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::volumioPushQueue Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::saveQueue Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::updateTrackBlock Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::getTrackBlock Jul 06 08:05:04 rivo volumio[3199]: info: CoreCommandRouter::volumioPlay Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::play index 0 Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::stop Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::play index undefined Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::getTrack 0 Jul 06 08:05:04 rivo volumio[3199]: info: CoreStateMachine::startPlaybackTimer Jul 06 08:05:04 rivo volumio[3199]: info: CorePlayQueue::getTrack 0 Jul 06 08:05:04 rivo volumio[3199]: info: [1751781904456] ControllerUPNPBrowser::clearAddPlayTrack Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 08:05:04 rivo volumio[3199]: info: Jul 06 08:05:04 rivo volumio[3199]: ---------------------------- MPD announces state update: player Jul 06 08:05:04 rivo volumio[3199]: info: sendMpdCommand stop took 445 milliseconds Jul 06 08:05:04 rivo volumio[3199]: info: ControllerMpd::getState Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand status Jul 06 08:05:04 rivo volumio[3199]: info: sendMpdCommand stop took 204 milliseconds Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand clear Jul 06 08:05:04 rivo volumio[3199]: info: Jul 06 08:05:04 rivo volumio[3199]: ---------------------------- MPD announces system playlist update Jul 06 08:05:04 rivo volumio[3199]: info: Ignoring MPD Status Update Jul 06 08:05:04 rivo volumio[3199]: info: sendMpdCommand status took 12 milliseconds Jul 06 08:05:04 rivo volumio[3199]: info: sendMpdCommand clear took 11 milliseconds Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::parseState Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.20:50002/transcoder/genericoder.cgi/id=3131.wav?format=WAV&downsample=false" Jul 06 08:05:04 rivo volumio[3199]: error: updateQueue error: null Jul 06 08:05:04 rivo volumio[3199]: info: ------------------------------ 21ms Jul 06 08:05:04 rivo volumio[3199]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 06 08:05:04 rivo volumio[3199]: verbose: ControllerMpd::parseTrackInfo Jul 06 08:05:04 rivo volumio[3199]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 06 08:05:04 rivo volumio[3199]: info: ------------------------------ 38ms Jul 06 08:05:04 rivo volumio[3199]: info: MCU Signalled Playback Inactive Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.20:50002/transcoder/genericoder.cgi/id=3131.wav?format=WAV&downsample=false" Jul 06 08:05:05 rivo volumio[3199]: info: Jul 06 08:05:05 rivo volumio[3199]: ---------------------------- MPD announces system playlist update Jul 06 08:05:05 rivo volumio[3199]: info: Ignoring MPD Status Update Jul 06 08:05:05 rivo volumio[3199]: info: sendMpdCommand add "http://192.168.1.20:50002/transcoder/genericoder.cgi/id=3131.wav?format=WAV&downsample=false" took 5 milliseconds Jul 06 08:05:05 rivo volumio[3199]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand play Jul 06 08:05:05 rivo volumio[3199]: info: ------------------------------ 8ms Jul 06 08:05:05 rivo volumio[3199]: info: sendMpdCommand play took 5 milliseconds Jul 06 08:05:05 rivo volumio[3199]: info: Jul 06 08:05:05 rivo volumio[3199]: ---------------------------- MPD announces state update: player Jul 06 08:05:05 rivo volumio[3199]: info: ControllerMpd::getState Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand status Jul 06 08:05:05 rivo volumio[3199]: info: Jul 06 08:05:05 rivo volumio[3199]: ---------------------------- MPD announces state update: player Jul 06 08:05:05 rivo volumio[3199]: info: sendMpdCommand status took 31 milliseconds Jul 06 08:05:05 rivo volumio[3199]: info: ControllerMpd::getState Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand status Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::parseState Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 08:05:05 rivo volumio[3199]: info: sendMpdCommand status took 3 milliseconds Jul 06 08:05:05 rivo volumio[3199]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::parseState Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::parseTrackInfo Jul 06 08:05:05 rivo volumio[3199]: info: ControllerMpd::pushState Jul 06 08:05:05 rivo volumio[3199]: info: CoreCommandRouter::servicePushState Jul 06 08:05:05 rivo volumio[3199]: info: CorePlayQueue::getTrack 0 Jul 06 08:05:05 rivo volumio[3199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=3131.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.20:50002/transcoder/genericoder.cgi/id=3131.wav?format=WAV&downsample=false","trackType":""} Jul 06 08:05:05 rivo volumio[3199]: verbose: CURRENT POSITION 0 Jul 06 08:05:05 rivo volumio[3199]: info: CoreStateMachine::syncState stateService play Jul 06 08:05:05 rivo volumio[3199]: info: CoreStateMachine::syncState currentStatus stop Jul 06 08:05:05 rivo volumio[3199]: info: ------------------------------ 53ms Jul 06 08:05:05 rivo volumio[3199]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 06 08:05:05 rivo volumio[3199]: verbose: ControllerMpd::parseTrackInfo Jul 06 08:05:05 rivo volumio[3199]: info: ControllerMpd::pushState Jul 06 08:05:05 rivo volumio[3199]: info: CoreCommandRouter::servicePushState Jul 06 08:05:05 rivo volumio[3199]: info: CorePlayQueue::getTrack 0 Jul 06 08:05:05 rivo volumio[3199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=3131.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.1.20:50002/transcoder/genericoder.cgi/id=3131.wav?format=WAV&downsample=false","trackType":""} Jul 06 08:05:05 rivo volumio[3199]: verbose: CURRENT POSITION 0 Jul 06 08:05:05 rivo volumio[3199]: info: CoreStateMachine::syncState stateService play Jul 06 08:05:05 rivo volumio[3199]: info: CoreStateMachine::syncState currentStatus play Jul 06 08:05:05 rivo volumio[3199]: info: Received an update from plugin. extracting info from payload Jul 06 08:05:05 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:05:05 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 08:05:05 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:05:05 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:05:05 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:05:05 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:05:05 rivo volumio[3199]: info: CoreStateMachine::pushState Jul 06 08:05:05 rivo volumio[3199]: info: CoreCommandRouter::volumioPushState Jul 06 08:05:05 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:05:05 rivo volumio[3199]: info: MRS: Pushing multiroomSync output update for this device Jul 06 08:05:05 rivo volumio[3199]: info: MRS: Pushing multiroomSync output Jul 06 08:05:05 rivo volumio[3199]: info: ------------------------------ 113ms Jul 06 08:05:05 rivo volumio[3199]: info: Signalling Playback active due to playback status change Jul 06 08:05:05 rivo volumio[3199]: info: MCU Signalled Playback Active Jul 06 08:05:07 rivo volumio[3199]: info: Executing endpoint metavolumio Jul 06 08:05:07 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 06 08:05:09 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 08:05:09 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 08:05:09 rivo volumio[3199]: info: Discovery: Getting this device information Jul 06 08:05:09 rivo volumio[3199]: info: CoreCommandRouter::volumioGetState Jul 06 08:05:09 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 08:05:27 rivo volumio[3199]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 06 08:05:27 rivo volumio[3199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 08:05:27 rivo volumio[3199]: TypeError: Cannot read property 'length' of undefined Jul 06 08:05:27 rivo volumio[3199]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 06 08:05:27 rivo volumio[3199]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 06 08:05:27 rivo volumio[3199]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 06 08:05:27 rivo volumio[3199]: at Parser.emit (events.js:400:28) Jul 06 08:05:27 rivo volumio[3199]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 06 08:05:27 rivo volumio[3199]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 06 08:05:27 rivo volumio[3199]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 06 08:05:27 rivo volumio[3199]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 06 08:05:27 rivo volumio[3199]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 06 08:05:27 rivo volumio[3199]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 06 08:05:27 rivo volumio[3199]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 06 08:05:27 rivo volumio[3199]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 06 08:05:27 rivo volumio[3199]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 06 08:05:27 rivo volumio[3199]: at IncomingMessage.emit (events.js:412:35) Jul 06 08:05:27 rivo volumio[3199]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 06 08:05:27 rivo volumio[3199]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 06 08:05:27 rivo volumio[3199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 08:05:28 rivo sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 08:04 Jul 06 08:05:28 rivo sudo[887]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 05:15:38 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="13b9c0f9785de91e97db9573691d3efe"