Feb 27 00:37:01 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:01 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:02 volumio volumio[1219]: info: CoreCommandRouter::volumioGetQueue Feb 27 00:37:02 volumio volumio[1219]: info: CoreStateMachine::getQueue Feb 27 00:37:02 volumio volumio[1219]: info: CorePlayQueue::getQueue Feb 27 00:37:05 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 27 00:37:05 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:13 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 27 00:37:13 volumio volumio[1219]: info: CURURI: music-library Feb 27 00:37:13 volumio mpd[1582]: exception: No database Feb 27 00:37:13 volumio volumio[1219]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Feb 27 00:37:13 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:15 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:15 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:16 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:16 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:17 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:17 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:19 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: Cannot compose Albumart path Feb 27 00:37:20 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22497 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22498 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22499 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22500 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22501 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22502 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22503 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22504 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22505 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22506 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22507 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22508 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22509 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22510 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17962 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17963 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17964 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17965 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17816 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17817 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17818 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17819 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17820 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17821 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24958 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24959 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24960 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24961 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24962 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24963 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24964 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13506 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13510 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13511 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13512 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13513 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13514 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13515 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13516 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13517 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13507 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13508 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13509 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13518 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13522 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13523 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13524 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13525 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13526 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13527 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13528 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13529 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13519 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13520 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13521 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13530 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13531 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13532 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13533 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13534 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13535 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13536 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13537 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13538 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13539 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13540 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13541 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13542 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13543 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13544 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13545 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13546 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13547 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13548 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13549 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13550 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13551 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13552 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13553 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24035 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24036 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24037 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24038 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24039 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24040 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24041 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24042 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24043 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24044 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24045 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24046 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24047 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24048 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24049 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24050 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24051 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24052 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24053 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24054 Feb 27 00:37:20 volumio volumio[1219]: info: Preloading song: upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24055 Feb 27 00:37:20 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22497 in service upnp_browser Feb 27 00:37:20 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22498 in service upnp_browser Feb 27 00:37:20 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22499 in service upnp_browser Feb 27 00:37:20 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22500 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22501 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22502 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22503 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22504 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22505 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22506 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22507 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22508 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22509 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@22510 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17962 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17963 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17964 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17965 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17816 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17817 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17818 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17819 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17820 in service upnp_browser Feb 27 00:37:21 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@17821 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24958 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24959 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24960 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24961 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24962 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24963 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@24964 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13506 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13510 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13511 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13512 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13513 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13514 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13515 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13516 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13517 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13507 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13508 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13509 in service upnp_browser Feb 27 00:37:22 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13518 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13522 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13523 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13524 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13525 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:23 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13526 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13527 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: Exploding uri upnp/http://192.168.1.101:50001/ContentDirectory/control@23$@13528 in service upnp_browser Feb 27 00:37:23 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:24 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.871+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.107:49762 Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.915+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.107:49762 @ 0x1801500" latency=-69.402794ms timeout=10s Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.915+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.915+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.107:49762 @ 0x1801500" latency=-68.474682ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 27 00:37:28 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:28 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:28 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.921+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" name=Volumio Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.922+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" language=fr Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.924+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" timezone=Europe/Paris Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.925+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" available=true connected=true macAddress=e4:5f:01:fc:79:f6 ip4Address=192.168.1.192/24 ip6Address= Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.926+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 27 00:37:28 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:28.927+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" setupComplete=true Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 00:37:28 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 00:37:28 volumio volumio[1219]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 27 00:37:28 volumio volumio[1219]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 27 00:37:29 volumio volumio[1219]: amixer -c 2 info | grep "vc4-hdmi-0" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Feb 27 00:37:29 volumio volumio[1219]: amixer -c 3 info | grep "vc4-hdmi-1" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Feb 27 00:37:29 volumio volumio[1219]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 27 00:37:29 volumio volumio[1219]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 27 00:37:29 volumio volumio[1219]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 27 00:37:29 volumio volumio[1219]: amixer -c 5 info | grep "SMSL USB AUDIO" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:01:00.0-1.3, high speed' Feb 27 00:37:29 volumio volumio[1219]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 27 00:37:29 volumio volumio[1219]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 27 00:37:29 volumio volumio[1219]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 27 00:37:29 volumio volumio[1219]: amixer -c 5 info | grep "SMSL USB AUDIO" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:01:00.0-1.3, high speed' Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.098+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" selectedOutputId=5 Feb 27 00:37:29 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:29 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:29 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.113+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" currentVersion=4.096 latestVersion=4.096 Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.113+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.107:49762 @ 0x1801500" status=UPDATE_STATUS_NONE progress=0 Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.113+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" userId= Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.113+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" providers=3 Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.641+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.107:49762 @ 0x1801500" latency=-69.092716ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.641+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.107:49762 @ 0x1801500" latency=-68.233913ms timeout=10s Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.641+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" Feb 27 00:37:29 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:29 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:29 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.645+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" name=Volumio Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.646+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" language=fr Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.649+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" timezone=Europe/Paris Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.650+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" available=true connected=true macAddress=e4:5f:01:fc:79:f6 ip4Address=192.168.1.192/24 ip6Address= Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.651+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.652+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" setupComplete=true Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 00:37:29 volumio volumio[1219]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 27 00:37:29 volumio volumio[1219]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 27 00:37:29 volumio volumio[1219]: amixer -c 2 info | grep "vc4-hdmi-0" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Feb 27 00:37:29 volumio volumio[1219]: amixer -c 3 info | grep "vc4-hdmi-1" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Feb 27 00:37:29 volumio volumio[1219]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 27 00:37:29 volumio volumio[1219]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 27 00:37:29 volumio volumio[1219]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 27 00:37:29 volumio volumio[1219]: amixer -c 5 info | grep "SMSL USB AUDIO" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:01:00.0-1.3, high speed' Feb 27 00:37:29 volumio volumio[1219]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 27 00:37:29 volumio volumio[1219]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 27 00:37:29 volumio volumio[1219]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 27 00:37:29 volumio volumio[1219]: amixer -c 5 info | grep "SMSL USB AUDIO" Feb 27 00:37:29 volumio volumio[1219]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:01:00.0-1.3, high speed' Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.816+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" selectedOutputId=5 Feb 27 00:37:29 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:29 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:29 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:29 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.833+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" currentVersion=4.096 latestVersion=4.096 Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.833+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.107:49762 @ 0x1801500" status=UPDATE_STATUS_NONE progress=0 Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.834+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" userId= Feb 27 00:37:29 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:29.834+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" providers=3 Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.704+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" plugins=63 Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.704+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" plugins=63 Feb 27 00:37:30 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.708+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" state=STATUS_STOPPED positionMs=0 volume=100 Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.708+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" id= title= Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.708+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" state=STATUS_STOPPED positionMs=0 volume=100 Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.708+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" id= title= Feb 27 00:37:30 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.711+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" state=STATUS_STOPPED positionMs=0 volume=100 Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.711+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" id= title= Feb 27 00:37:30 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:30.730+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.107:49762 @ 0x1801500" latency=-67.594465ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 27 00:37:31 volumio sudo[5129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 00:37:31 volumio sudo[5129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:31 volumio sudo[5129]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:31 volumio sudo[5131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 00:37:31 volumio sudo[5131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:31 volumio sudo[5131]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:31 volumio volumio[1219]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.107 UA: Mozilla/5.0 (Linux; Android 16; A065 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 27 00:37:31 volumio sudo[5135]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 00:37:31 volumio sudo[5135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:31 volumio sudo[5135]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:31 volumio sudo[5137]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 00:37:31 volumio sudo[5137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:31 volumio sudo[5137]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:31 volumio volumio[1219]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.107 UA: Mozilla/5.0 (Linux; Android 16; A065 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:31 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 27 00:37:31 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:31 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:31 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:31 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:31 volumio volumio[1219]: info: Listing playlists Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 00:37:31 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 27 00:37:32 volumio volumio[1219]: info: CoreCommandRouter::volumioGetQueue Feb 27 00:37:32 volumio volumio[1219]: info: CoreStateMachine::getQueue Feb 27 00:37:32 volumio volumio[1219]: info: CorePlayQueue::getQueue Feb 27 00:37:33 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 27 00:37:33 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:33 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:33 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:33 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:33 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:33 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:33 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:33 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:33 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 27 00:37:33 volumio volumio[1219]: info: CURURI: music-library Feb 27 00:37:33 volumio mpd[1582]: exception: No database Feb 27 00:37:33 volumio volumio[1219]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Feb 27 00:37:33 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:34 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:34 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:34 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:34 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:36 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:36 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:37 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:37 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:38 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:38 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:39 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:39 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:39 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 27 00:37:40 volumio volumio[1219]: info: Preload queue cleared Feb 27 00:37:41 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 27 00:37:46 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:46 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 27 00:37:46 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 27 00:37:46 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 27 00:37:46 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 27 00:37:47 volumio volumio[1219]: info: Received Get System Version Feb 27 00:37:47 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 00:37:49 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 00:37:49 volumio volumio[1219]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 27 00:37:49 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 27 00:37:49 volumio volumio[1219]: info: Received Get System Version Feb 27 00:37:49 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 00:37:49 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:49 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:49 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:49 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:49 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:49 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:49 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:52 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Feb 27 00:37:52 volumio volumio[1219]: info: CoreCommandRouter::Reload Ui Feb 27 00:37:52 volumio sudo[5212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 00:37:52 volumio sudo[5212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:52 volumio sudo[5212]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:52 volumio sudo[5214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 00:37:52 volumio sudo[5214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:52 volumio sudo[5214]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:53 volumio volumio[1219]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.166 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 27 00:37:53 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:53 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:53 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:53 volumio volumio-remote-updater[781]: Test mode disabled Feb 27 00:37:53 volumio volumio-remote-updater[781]: Alpha mode disabled Feb 27 00:37:53 volumio volumio-remote-updater[781]: Alpha legacy test mode disabled Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Retrieving Cloud Streaming UI Feb 27 00:37:53 volumio volumio[1219]: info: Getting Tidal Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Getting Qobuz Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Getting Spotify Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Saving Spotify Acccount Feb 27 00:37:53 volumio volumio[1219]: info: Got Tidal Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: Got it Feb 27 00:37:53 volumio volumio[1219]: info: Got it Feb 27 00:37:53 volumio volumio[1219]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 27 00:37:53 volumio volumio[1219]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 27 00:37:53 volumio sudo[5227]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 00:37:53 volumio sudo[5227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:53 volumio sudo[5227]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:53 volumio sudo[5229]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 00:37:53 volumio sudo[5229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:53 volumio sudo[5229]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:53 volumio volumio[1219]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.107 UA: Mozilla/5.0 (Linux; Android 16; A065 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 27 00:37:53 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:53 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:53 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:53 volumio volumio-remote-updater[781]: Test mode disabled Feb 27 00:37:53 volumio volumio-remote-updater[781]: Alpha mode disabled Feb 27 00:37:53 volumio volumio-remote-updater[781]: Alpha legacy test mode disabled Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Retrieving Cloud Streaming UI Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 00:37:53 volumio volumio[1219]: info: Getting Tidal Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Getting Qobuz Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Getting Spotify Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: info: Saving Spotify Acccount Feb 27 00:37:53 volumio volumio[1219]: info: Got Tidal Cloud Configuration Feb 27 00:37:53 volumio volumio[1219]: info: Got it Feb 27 00:37:53 volumio volumio[1219]: info: Got it Feb 27 00:37:53 volumio volumio[1219]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:53 volumio volumio[1219]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 27 00:37:53 volumio volumio[1219]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 27 00:37:53 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 27 00:37:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 27 00:37:54 volumio volumio[1219]: info: Loading i18n strings for locale fr Feb 27 00:37:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 27 00:37:54 volumio volumio[1219]: info: Setting timezone to Europe/Paris Feb 27 00:37:54 volumio sudo[5239]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 27 00:37:54 volumio sudo[5239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio sudo[5239]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio sudo[5243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Paris /etc/localtime Feb 27 00:37:54 volumio sudo[5243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio sudo[5243]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio sudo[5247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 27 00:37:54 volumio sudo[5247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio sudo[5247]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio sudo[5251]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Feb 27 00:37:54 volumio sudo[5251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio dbus-daemon[772]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.25' (uid=0 pid=5252 comm="/usr/bin/timedatectl set-timezone Europe/Paris") Feb 27 00:37:54 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Feb 27 00:37:54 volumio dbus-daemon[772]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 27 00:37:54 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Feb 27 00:37:54 volumio sudo[5251]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio volumio[1219]: Updating browse sources language Feb 27 00:37:54 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:37:54 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:37:54 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:37:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:37:54 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:37:54 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:37:54 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:37:54 volumio volumio[1219]: info: Fetching Streaming Services browse cache Feb 27 00:37:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 27 00:37:54 volumio volumio[1219]: info: Loading i18n strings for locale fr Feb 27 00:37:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 27 00:37:54 volumio volumio[1219]: info: Setting timezone to Europe/Paris Feb 27 00:37:54 volumio sudo[5256]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 27 00:37:54 volumio sudo[5256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio sudo[5256]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio sudo[5260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Paris /etc/localtime Feb 27 00:37:54 volumio sudo[5260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio sudo[5260]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio sudo[5265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 27 00:37:54 volumio sudo[5265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio sudo[5265]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio sudo[5271]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Feb 27 00:37:54 volumio sudo[5271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:54 volumio sudo[5271]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:55 volumio volumio[1219]: Updating browse sources language Feb 27 00:37:55 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:37:55 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:37:55 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:37:55 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:37:55 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:37:55 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:37:55 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:37:55 volumio volumio[1219]: info: Fetching Streaming Services browse cache Feb 27 00:37:55 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:55 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:56 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:37:57 volumio volumio5-onboarding[1565]: time=2026-02-27T00:37:57.776+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.107:49762 @ 0x1801500" latency=-26.817705ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 27 00:37:57 volumio sudo[5275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 00:37:57 volumio sudo[5275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:57 volumio sudo[5275]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:57 volumio sudo[5277]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 00:37:57 volumio sudo[5277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:57 volumio sudo[5277]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:57 volumio volumio[1219]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.107 UA: Mozilla/5.0 (Linux; Android 16; A065 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 27 00:37:57 volumio volumio[1219]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 00:37:57 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:37:57 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 27 00:37:57 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 27 00:37:58 volumio volumio[1219]: info: Received Get System Info Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:37:58 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:37:58 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:37:58 volumio volumio-remote-updater[781]: Test mode disabled Feb 27 00:37:58 volumio volumio-remote-updater[781]: Alpha mode disabled Feb 27 00:37:58 volumio volumio-remote-updater[781]: Alpha legacy test mode disabled Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:58 volumio volumio[1219]: info: Retrieving Cloud Streaming UI Feb 27 00:37:58 volumio volumio[1219]: info: Getting Tidal Cloud Configuration Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:58 volumio volumio[1219]: info: Getting Qobuz Cloud Configuration Feb 27 00:37:58 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:58 volumio volumio[1219]: info: Getting Spotify Cloud Configuration Feb 27 00:37:58 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:58 volumio volumio[1219]: info: Saving Spotify Acccount Feb 27 00:37:58 volumio volumio[1219]: info: Got Tidal Cloud Configuration Feb 27 00:37:58 volumio volumio[1219]: info: Got it Feb 27 00:37:58 volumio volumio[1219]: info: Got it Feb 27 00:37:58 volumio volumio[1219]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:37:58 volumio volumio[1219]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 27 00:37:58 volumio volumio[1219]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 27 00:37:58 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 27 00:37:59 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 27 00:37:59 volumio volumio[1219]: info: Loading i18n strings for locale fr Feb 27 00:37:59 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 27 00:37:59 volumio volumio[1219]: info: Setting timezone to Europe/Paris Feb 27 00:37:59 volumio sudo[5293]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 27 00:37:59 volumio sudo[5293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:59 volumio sudo[5293]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:59 volumio sudo[5297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Paris /etc/localtime Feb 27 00:37:59 volumio sudo[5297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:59 volumio sudo[5297]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:59 volumio sudo[5301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 27 00:37:59 volumio sudo[5301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:59 volumio sudo[5301]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:59 volumio sudo[5307]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Feb 27 00:37:59 volumio sudo[5307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:37:59 volumio sudo[5307]: pam_unix(sudo:session): session closed for user root Feb 27 00:37:59 volumio volumio[1219]: Updating browse sources language Feb 27 00:37:59 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:37:59 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:37:59 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:37:59 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:37:59 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:37:59 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:37:59 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:37:59 volumio volumio[1219]: info: Fetching Streaming Services browse cache Feb 27 00:37:59 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:38:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 00:38:03 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 27 00:38:03 volumio volumio[1219]: info: Loading i18n strings for locale fr Feb 27 00:38:03 volumio volumio[1219]: Updating browse sources language Feb 27 00:38:03 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:38:03 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:38:03 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:38:03 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 00:38:03 volumio volumio[1219]: Cannot find translation for source YouTube Music Feb 27 00:38:03 volumio volumio[1219]: Cannot find translation for source Radio Paradise Feb 27 00:38:03 volumio volumio[1219]: Cannot find translation for source Randomizer Feb 27 00:38:03 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 27 00:38:03 volumio volumio[1219]: info: Setting timezone to Europe/Paris Feb 27 00:38:03 volumio sudo[5326]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 27 00:38:03 volumio sudo[5326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:03 volumio sudo[5326]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:03 volumio sudo[5330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Paris /etc/localtime Feb 27 00:38:03 volumio sudo[5330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:03 volumio sudo[5330]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:03 volumio sudo[5334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 27 00:38:03 volumio sudo[5334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:03 volumio sudo[5334]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:03 volumio sudo[5338]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Feb 27 00:38:03 volumio sudo[5338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:03 volumio sudo[5338]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:03 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 27 00:38:03 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 27 00:38:03 volumio sudo[5341]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 27 00:38:03 volumio sudo[5341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:03 volumio volumio[1219]: info: Fetching Streaming Services browse cache Feb 27 00:38:05 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 27 00:38:05 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 27 00:38:05 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 27 00:38:05 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 00:38:05 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 00:38:06 volumio sudo[5341]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:06 volumio volumio5-onboarding[1565]: time=2026-02-27T00:38:06.425+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 27 00:38:06 volumio volumio[1219]: info: Received Get System Info Feb 27 00:38:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 00:38:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 00:38:06 volumio volumio[1219]: info: Discovery: Getting this device information Feb 27 00:38:06 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:38:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:38:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 00:38:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 27 00:38:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 27 00:38:07 volumio volumio[1219]: info: Preparing to save Alsa Options, stopping services first Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::volumioGetState Feb 27 00:38:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPause Feb 27 00:38:07 volumio volumio[1219]: info: CoreStateMachine::pause Feb 27 00:38:07 volumio volumio[1219]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"SMSL USB AUDIO"},"disallowPush":true} Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 00:38:07 volumio volumio[1219]: info: Device 5 does not have any Mixer Control Available Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 27 00:38:07 volumio volumio[1219]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 27 00:38:07 volumio volumio[1219]: info: Disabling external Volume Control Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:38:07 volumio volumio[1219]: info: Retrieving Cloud Streaming UI Feb 27 00:38:07 volumio volumio[1219]: info: CoreStateMachine::pushState Feb 27 00:38:07 volumio volumio[1219]: info: CorePlayQueue::getTrack 0 Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Feb 27 00:38:07 volumio volumio5-onboarding[1565]: time=2026-02-27T00:38:07.227+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" state=STATUS_STOPPED positionMs=0 volume=100 Feb 27 00:38:07 volumio volumio5-onboarding[1565]: time=2026-02-27T00:38:07.228+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49762 @ 0x1801500" id= title= Feb 27 00:38:07 volumio volumio[1219]: info: Preparing to generate the ALSA configuration file Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 27 00:38:07 volumio volumio[1219]: info: Getting Tidal Cloud Configuration Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:38:07 volumio volumio[1219]: info: Getting Qobuz Cloud Configuration Feb 27 00:38:07 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:38:07 volumio volumio[1219]: info: Getting Spotify Cloud Configuration Feb 27 00:38:07 volumio volumio[1219]: info: Asking plugin for UI Config Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:38:07 volumio volumio[1219]: info: Saving Spotify Acccount Feb 27 00:38:07 volumio volumio[1219]: info: Got Tidal Cloud Configuration Feb 27 00:38:07 volumio volumio[1219]: info: Got it Feb 27 00:38:07 volumio volumio[1219]: info: Got it Feb 27 00:38:07 volumio volumio[1219]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 27 00:38:07 volumio volumio[1219]: info: Asound.conf file unchanged, so no further update is needed Feb 27 00:38:07 volumio volumio[1219]: info: Output device has changed, restarting MPD Feb 27 00:38:07 volumio volumio[1219]: info: Output device has changed, restarting Shairport Sync Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 00:38:07 volumio sudo[5365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 00:38:07 volumio sudo[5365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:07 volumio sudo[5365]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:07 volumio volumio[1219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 00:38:07 volumio sudo[5368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 00:38:07 volumio sudo[5368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:07 volumio volumio[1219]: info: MPD Permissions set Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 00:38:07 volumio volumio[1219]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 00:38:07 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 00:38:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 27 00:38:07 volumio volumio[1219]: info: Starting Shairport Sync Feb 27 00:38:07 volumio sudo[5380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 00:38:07 volumio sudo[5380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 27 00:38:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 27 00:38:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 27 00:38:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 27 00:38:07 volumio systemd[1]: shairport-sync.service: Consumed 4.470s CPU time. Feb 27 00:38:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 27 00:38:07 volumio sudo[5380]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:07 volumio volumio[1219]: info: Shairport-Sync Started Feb 27 00:38:07 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 27 00:38:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 27 00:38:07 volumio systemd[1]: mpd.service: Consumed 3.098s CPU time. Feb 27 00:38:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 27 00:38:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 27 00:38:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 27 00:38:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 27 00:38:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 27 00:38:07 volumio sudo[5398]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 27 00:38:07 volumio sudo[5398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 27 00:38:07 volumio sudo[5399]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 27 00:38:07 volumio sudo[5398]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:08 volumio volumio[1219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 00:38:08 volumio volumio[1219]: TypeError: Cannot read properties of null (reading 'logger') Feb 27 00:38:08 volumio volumio[1219]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:65:86) Feb 27 00:38:08 volumio volumio[1219]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_stdLogError (/data/plugins/music_service/squeezelite_mc/dist/index.js:716:36) Feb 27 00:38:08 volumio volumio[1219]: at /data/plugins/music_service/squeezelite_mc/dist/index.js:1216:121 Feb 27 00:38:08 volumio volumio[1219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 00:38:09 volumio mpd[5400]: 2026-02-27T00:38:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 27 00:38:09 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 27 00:38:09 volumio sudo[5368]: pam_unix(sudo:session): session closed for user root Feb 27 00:38:09 volumio sudo[5417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-27 00:37' Feb 27 00:38:09 volumio sudo[5417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"