-- Logs begin at Sat 2024-07-13 18:25:04 CEST, end at Sat 2024-07-13 18:44:20 CEST. --
Jul 13 18:43:09 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri
Jul 13 18:43:09 volumio volumio[741]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres_category%22%2C%22params%22%3A%22ggMPOg1uXzIxYkNac21YZ2Z0%22%7D%7D
Jul 13 18:43:09 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:09 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:09 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:09 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:09 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:09 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:09 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:16 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri
Jul 13 18:43:16 volumio volumio[741]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres_category%22%2C%22params%22%3A%22ggMPOg1uXzIxYkNac21YZ2Z0%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_kWiJXUNLZM9EyS3GBGznl1ku8_cOos97U%22%2C%22params%22%3A%22wAEB%22%7D%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22VLRDCLAK5uy_kWiJXUNLZM9EyS3GBGznl1ku8_cOos97U%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D
Jul 13 18:43:18 volumio volumio[741]: info: [ytmusic] PlaylistModel is going to recursively fetch continuation items for playlist with endpoint: {"type":"browse","payload":{"browseId":"VLRDCLAK5uy_kWiJXUNLZM9EyS3GBGznl1ku8_cOos97U"},"pageType":"MUSIC_PAGE_TYPE_PLAYLIST"}).
Jul 13 18:43:18 volumio volumio[741]: info: [ytmusic] Fetched 42 continuation items.
Jul 13 18:43:18 volumio volumio[741]: info: [ytmusic] Total 42 continuation items fetched. Total items in playlist: 142.
Jul 13 18:43:18 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:18 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:38 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri
Jul 13 18:43:38 volumio volumio[741]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres_category%22%2C%22params%22%3A%22ggMPOg1uXzIxYkNac21YZ2Z0%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_n0oLcyKJhNW8BmrnMySAoVuLjRZfgozG0%22%2C%22params%22%3A%22wAEB%22%7D%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22VLRDCLAK5uy_n0oLcyKJhNW8BmrnMySAoVuLjRZfgozG0%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D
Jul 13 18:43:40 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:40 volumio volumio[741]: info: Preload queue cleared
Jul 13 18:43:43 volumio volumio[741]: info: Listing playlists
Jul 13 18:43:59 volumio volumio[741]: info: CorePlayQueue::getTrack 4
Jul 13 18:43:59 volumio volumio[741]: info: CorePlayQueue::getTrack 5
Jul 13 18:43:59 volumio volumio[741]: info: Prefetching next song
Jul 13 18:43:59 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-8xgn5uxa-quhz.googlevideo.com/videoplayback?expire=1720910639&ei=z66SZq6lIeTOi9oPjIKksAU&ip=88.130.118.232&id=o-AGH8gvh5exUz8698AYOURrfGfrTwnURfbkjU8PoeVFcE&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=EC&mm=31%2C26&mn=sn-8xgn5uxa-quhz%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=1&pl=19&ctier=A&pfa=5&gcr=de&initcwndbps=1817500&hightc=yes&siu=1&bui=AXc671I-AT5qhPkPVpEsztJtDLPSZJCn87e6zYmdXfYI08Wf2UeQKZZHdRKf591HGnzaSTK9yg&spc=NO7bAR7fE2VQY7vBRJyR4UPHG8fHyiIEJhBuJ45SEKJFc2-FxGzbKlXKN94OrVM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ftQmzalY1IP8e5TY99enbd8Q&rqh=1&gir=yes&clen=5077530&dur=152.761&lmt=1714700242990795&mt=1720888632&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=rSsLtSadrGfNiQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLJ3XIk3pYozoFxEU_x8oKgIYj_ANmpglw2yEneStbTYCIQCDEcg1FmhVOH4NEtA94gTCjWfR-pGImR8gXmTLQZyORg%3D%3D&sig=AJfQdSswRgIhAO-z1R9w0x8bApoMYFYfqFX2-DYngXHhITPqCtKdIDb1AiEAtC__PeoRRZxzB1vbDaHcgm-f-v92bhecpTlBbK-ocXI%3D&cver=1.20211213.00.00&t.YouTube"
Jul 13 18:43:59 volumio volumio[741]: info:
Jul 13 18:43:59 volumio volumio[741]: ---------------------------- MPD announces system playlist update
Jul 13 18:43:59 volumio volumio[741]: info: Ignoring MPD Status Update
Jul 13 18:43:59 volumio volumio[741]: info: sendMpdCommand addid "https://rr1---sn-8xgn5uxa-quhz.googlevideo.com/videoplayback?expire=1720910639&ei=z66SZq6lIeTOi9oPjIKksAU&ip=88.130.118.232&id=o-AGH8gvh5exUz8698AYOURrfGfrTwnURfbkjU8PoeVFcE&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=EC&mm=31%2C26&mn=sn-8xgn5uxa-quhz%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=1&pl=19&ctier=A&pfa=5&gcr=de&initcwndbps=1817500&hightc=yes&siu=1&bui=AXc671I-AT5qhPkPVpEsztJtDLPSZJCn87e6zYmdXfYI08Wf2UeQKZZHdRKf591HGnzaSTK9yg&spc=NO7bAR7fE2VQY7vBRJyR4UPHG8fHyiIEJhBuJ45SEKJFc2-FxGzbKlXKN94OrVM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ftQmzalY1IP8e5TY99enbd8Q&rqh=1&gir=yes&clen=5077530&dur=152.761&lmt=1714700242990795&mt=1720888632&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=rSsLtSadrGfNiQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLJ3XIk3pYozoFxEU_x8oKgIYj_ANmpglw2yEneStbTYCIQCDEcg1FmhVOH4NEtA94gTCjWfR-pGImR8gXmTLQZyORg%3D%3D&sig=AJfQdSswRgIhAO-z1R9w0x8bApoMYFYfqFX2-DYngXHhITPqCtKdIDb1AiEAtC__PeoRRZxzB1vbDaHcgm-f-v92bhecpTlBbK-ocXI%3D&cver=1.20211213.00.00&t.YouTube" took 8 milliseconds
Jul 13 18:43:59 volumio volumio[741]: verbose: MPD COMMAND [object Object]
Jul 13 18:43:59 volumio volumio[741]: verbose: MPD COMMAND [object Object]
Jul 13 18:43:59 volumio volumio[741]: info:
Jul 13 18:43:59 volumio volumio[741]: ---------------------------- MPD announces system playlist update
Jul 13 18:43:59 volumio volumio[741]: info: Ignoring MPD Status Update
Jul 13 18:43:59 volumio volumio[741]: info: ------------------------------ 10ms
Jul 13 18:43:59 volumio volumio[741]: info: [ytmusic-play] Prefetched and added track to MPD queue: Pass me the rum
Jul 13 18:43:59 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand consume 1
Jul 13 18:43:59 volumio volumio[741]: info:
Jul 13 18:43:59 volumio volumio[741]: ---------------------------- MPD announces state update: options
Jul 13 18:43:59 volumio volumio[741]: info: ------------------------------ 3ms
Jul 13 18:43:59 volumio volumio[741]: info: sendMpdCommand consume 1 took 2 milliseconds
Jul 13 18:43:59 volumio volumio[741]: info: ControllerMpd::getState
Jul 13 18:43:59 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand status
Jul 13 18:43:59 volumio volumio[741]: info: sendMpdCommand status took 2 milliseconds
Jul 13 18:43:59 volumio volumio[741]: verbose: ControllerMpd::parseState
Jul 13 18:43:59 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 13 18:43:59 volumio volumio[741]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 13 18:43:59 volumio volumio[741]: verbose: ControllerMpd::parseTrackInfo
Jul 13 18:43:59 volumio volumio[741]: info: ControllerMpd::pushState
Jul 13 18:43:59 volumio volumio[741]: info: CoreCommandRouter::servicePushState
Jul 13 18:43:59 volumio volumio[741]: info: CorePlayQueue::getTrack 4
Jul 13 18:43:59 volumio volumio[741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":172107,"duration":175,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Get Down","artist":"Alex Menco","album":"Get Down","uri":"https://rr2---sn-8xgn5uxa-quhz.googlevideo.com/videoplayback?expire=1720910500&ei=RK6SZu3xNJSqp-oPub6D0AU&ip=88.130.118.232&id=o-AKOXknDtbFnfMKJ3LRwj4LzuCwBmL9HhTxngD5RAiqNL&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=EH&mm=31%2C26&mn=sn-8xgn5uxa-quhz%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=2&pl=19&ctier=A&pfa=5&gcr=de&initcwndbps=1783750&hightc=yes&siu=1&bui=AXc671ILvqw-URFe81FP7Pcwxsp1oN-rqO80bSSLgqcgDwzUvI5ZhbdvXOMmvrmRr4lAp-19OQ&spc=NO7bAUTa8oyOQUfRJDIAtAiz8G_u2zPJyiod3NJ4sCRqesXFq2n5P8lIqjZfsHY&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=K4Ezrasc1C6hxEFDopAxZFAQ&rqh=1&gir=yes&clen=5884875&dur=175.221&lmt=1714586370386055&mt=1720888386&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=9TCx0-BpRmzLYw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgWsgczr4woamGmYtsqqh_IfPFeqGEAhd3dyCxi1MoG_ACIQCIN2CwTFfGK-KrDLfAj84MRvsJ5EcSUYX2VySFnqf1Zw%3D%3D&sig=AJfQdSswRgIhANn_EHMfARDpHkccHUkW0tWGOomy6T2hT_tah75jE2mrAiEAvKbM0B5wobqYS6U_8XL176T0Pa2eQZaBThVxuzJXad4%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 13 18:43:59 volumio volumio[741]: verbose: CURRENT POSITION 4
Jul 13 18:43:59 volumio volumio[741]: info: CoreStateMachine::syncState stateService play
Jul 13 18:43:59 volumio volumio[741]: info: CoreStateMachine::syncState currentStatus play
Jul 13 18:43:59 volumio volumio[741]: info: Received an update from plugin. extracting info from payload
Jul 13 18:43:59 volumio volumio[741]: info: CoreStateMachine::pushState
Jul 13 18:43:59 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 13 18:43:59 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Jul 13 18:43:59 volumio volumio[741]: info: MRS: Pushing multiroomSync output update for this device
Jul 13 18:43:59 volumio volumio[741]: info: MRS: Pushing multiroomSync output
Jul 13 18:43:59 volumio volumio[741]: info: CoreStateMachine::pushState
Jul 13 18:43:59 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Jul 13 18:43:59 volumio volumio[741]: info: MRS: Pushing multiroomSync output update for this device
Jul 13 18:43:59 volumio volumio[741]: info: MRS: Pushing multiroomSync output
Jul 13 18:43:59 volumio volumio[741]: info: ------------------------------ 23ms
Jul 13 18:44:02 volumio volumio[741]: info:
Jul 13 18:44:02 volumio volumio[741]: ---------------------------- MPD announces system playlist update
Jul 13 18:44:02 volumio volumio[741]: info: Ignoring MPD Status Update
Jul 13 18:44:02 volumio volumio[741]: info: ControllerMpd::getState
Jul 13 18:44:02 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand status
Jul 13 18:44:02 volumio volumio[741]: info:
Jul 13 18:44:02 volumio volumio[741]: ---------------------------- MPD announces state update: player
Jul 13 18:44:02 volumio volumio[741]: info: ControllerMpd::getState
Jul 13 18:44:02 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand status
Jul 13 18:44:03 volumio volumio[741]: info: sendMpdCommand status took 3 milliseconds
Jul 13 18:44:03 volumio volumio[741]: info: ------------------------------ 4ms
Jul 13 18:44:03 volumio volumio[741]: info: sendMpdCommand status took 3 milliseconds
Jul 13 18:44:03 volumio volumio[741]: verbose: ControllerMpd::parseState
Jul 13 18:44:03 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 13 18:44:03 volumio volumio[741]: verbose: ControllerMpd::parseState
Jul 13 18:44:03 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 13 18:44:03 volumio volumio[741]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 13 18:44:03 volumio volumio[741]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 13 18:44:03 volumio volumio[741]: verbose: ControllerMpd::parseTrackInfo
Jul 13 18:44:03 volumio volumio[741]: verbose: ControllerMpd::parseTrackInfo
Jul 13 18:44:03 volumio volumio[741]: info: ControllerMpd::pushState
Jul 13 18:44:03 volumio volumio[741]: info: CoreCommandRouter::servicePushState
Jul 13 18:44:03 volumio volumio[741]: info: CorePlayQueue::getTrack 4
Jul 13 18:44:03 volumio volumio[741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32,"duration":153,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pass me the rum","artist":"Alfons, Marnik und Jungle Jonsson","album":"Pass me the rum","uri":"https://rr1---sn-8xgn5uxa-quhz.googlevideo.com/videoplayback?expire=1720910639&ei=z66SZq6lIeTOi9oPjIKksAU&ip=88.130.118.232&id=o-AGH8gvh5exUz8698AYOURrfGfrTwnURfbkjU8PoeVFcE&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=EC&mm=31%2C26&mn=sn-8xgn5uxa-quhz%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=1&pl=19&ctier=A&pfa=5&gcr=de&initcwndbps=1817500&hightc=yes&siu=1&bui=AXc671I-AT5qhPkPVpEsztJtDLPSZJCn87e6zYmdXfYI08Wf2UeQKZZHdRKf591HGnzaSTK9yg&spc=NO7bAR7fE2VQY7vBRJyR4UPHG8fHyiIEJhBuJ45SEKJFc2-FxGzbKlXKN94OrVM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ftQmzalY1IP8e5TY99enbd8Q&rqh=1&gir=yes&clen=5077530&dur=152.761&lmt=1714700242990795&mt=1720888632&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=rSsLtSadrGfNiQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLJ3XIk3pYozoFxEU_x8oKgIYj_ANmpglw2yEneStbTYCIQCDEcg1FmhVOH4NEtA94gTCjWfR-pGImR8gXmTLQZyORg%3D%3D&sig=AJfQdSswRgIhAO-z1R9w0x8bApoMYFYfqFX2-DYngXHhITPqCtKdIDb1AiEAtC__PeoRRZxzB1vbDaHcgm-f-v92bhecpTlBbK-ocXI%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 13 18:44:03 volumio volumio[741]: verbose: CURRENT POSITION 4
Jul 13 18:44:03 volumio volumio[741]: info: CoreStateMachine::syncState stateService play
Jul 13 18:44:03 volumio volumio[741]: info: CoreStateMachine::syncState currentStatus play
Jul 13 18:44:03 volumio volumio[741]: info: Received an update from plugin. extracting info from payload
Jul 13 18:44:03 volumio volumio[741]: info: CoreStateMachine::pushState
Jul 13 18:44:03 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 13 18:44:03 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Jul 13 18:44:03 volumio volumio[741]: info: MRS: Pushing multiroomSync output update for this device
Jul 13 18:44:03 volumio volumio[741]: info: MRS: Pushing multiroomSync output
Jul 13 18:44:03 volumio volumio[741]: info: CoreStateMachine::pushState
Jul 13 18:44:03 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Jul 13 18:44:03 volumio volumio[741]: info: MRS: Pushing multiroomSync output update for this device
Jul 13 18:44:03 volumio volumio[741]: info: MRS: Pushing multiroomSync output
Jul 13 18:44:03 volumio volumio[741]: info: ------------------------------ 39ms
Jul 13 18:44:03 volumio volumio[741]: info: CoreStateMachine::startPlaybackTimer
Jul 13 18:44:03 volumio volumio[741]: info: CorePlayQueue::getTrack 5
Jul 13 18:44:04 volumio volumio[741]: info: CoreStateMachine::pushState
Jul 13 18:44:04 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 13 18:44:04 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Jul 13 18:44:04 volumio volumio[741]: info: MRS: Pushing multiroomSync output update for this device
Jul 13 18:44:04 volumio volumio[741]: info: MRS: Pushing multiroomSync output
Jul 13 18:44:04 volumio volumio[741]: info: CorePlayQueue::getTrack 5
Jul 13 18:44:04 volumio volumio[741]: info: ControllerMpd::getState
Jul 13 18:44:04 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand status
Jul 13 18:44:04 volumio volumio[741]: info: sendMpdCommand status took 9 milliseconds
Jul 13 18:44:04 volumio volumio[741]: verbose: ControllerMpd::parseState
Jul 13 18:44:04 volumio volumio[741]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 13 18:44:04 volumio volumio[741]: info: sendMpdCommand playlistinfo took 5 milliseconds
Jul 13 18:44:04 volumio volumio[741]: verbose: ControllerMpd::parseTrackInfo
Jul 13 18:44:04 volumio volumio[741]: info: ControllerMpd::pushState
Jul 13 18:44:04 volumio volumio[741]: info: CoreCommandRouter::servicePushState
Jul 13 18:44:04 volumio volumio[741]: info: CorePlayQueue::getTrack 5
Jul 13 18:44:04 volumio volumio[741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1282,"duration":153,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pass me the rum","artist":"Alfons, Marnik und Jungle Jonsson","album":"Pass me the rum","uri":"https://rr1---sn-8xgn5uxa-quhz.googlevideo.com/videoplayback?expire=1720910639&ei=z66SZq6lIeTOi9oPjIKksAU&ip=88.130.118.232&id=o-AGH8gvh5exUz8698AYOURrfGfrTwnURfbkjU8PoeVFcE&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=EC&mm=31%2C26&mn=sn-8xgn5uxa-quhz%2Csn-5hne6n6e&ms=au%2Conr&mv=m&mvi=1&pl=19&ctier=A&pfa=5&gcr=de&initcwndbps=1817500&hightc=yes&siu=1&bui=AXc671I-AT5qhPkPVpEsztJtDLPSZJCn87e6zYmdXfYI08Wf2UeQKZZHdRKf591HGnzaSTK9yg&spc=NO7bAR7fE2VQY7vBRJyR4UPHG8fHyiIEJhBuJ45SEKJFc2-FxGzbKlXKN94OrVM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ftQmzalY1IP8e5TY99enbd8Q&rqh=1&gir=yes&clen=5077530&dur=152.761&lmt=1714700242990795&mt=1720888632&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=rSsLtSadrGfNiQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLJ3XIk3pYozoFxEU_x8oKgIYj_ANmpglw2yEneStbTYCIQCDEcg1FmhVOH4NEtA94gTCjWfR-pGImR8gXmTLQZyORg%3D%3D&sig=AJfQdSswRgIhAO-z1R9w0x8bApoMYFYfqFX2-DYngXHhITPqCtKdIDb1AiEAtC__PeoRRZxzB1vbDaHcgm-f-v92bhecpTlBbK-ocXI%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 13 18:44:04 volumio volumio[741]: verbose: CURRENT POSITION 5
Jul 13 18:44:04 volumio volumio[741]: info: CoreStateMachine::syncState stateService play
Jul 13 18:44:04 volumio volumio[741]: info: CoreStateMachine::syncState currentStatus play
Jul 13 18:44:04 volumio volumio[741]: info: Received an update from plugin. extracting info from payload
Jul 13 18:44:04 volumio volumio[741]: info: CoreStateMachine::pushState
Jul 13 18:44:04 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 13 18:44:04 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Jul 13 18:44:04 volumio volumio[741]: info: MRS: Pushing multiroomSync output update for this device
Jul 13 18:44:04 volumio volumio[741]: info: MRS: Pushing multiroomSync output
Jul 13 18:44:04 volumio volumio[741]: info: CoreStateMachine::pushState
Jul 13 18:44:04 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Jul 13 18:44:04 volumio volumio[741]: info: MRS: Pushing multiroomSync output update for this device
Jul 13 18:44:04 volumio volumio[741]: info: MRS: Pushing multiroomSync output
Jul 13 18:44:19 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: ytmusic , explodeUri
Jul 13 18:44:19 volumio volumio[741]: info: [ytmusic-browse] explodeUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Zj1RgtJT670%22%2C%22playlistId%22%3A%22RDCLAK5uy_n0oLcyKJhNW8BmrnMySAoVuLjRZfgozG0%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D
Jul 13 18:44:20 volumio volumio[741]: info: Saving Cloud item Elektronik 7.24
Jul 13 18:44:20 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem
Jul 13 18:44:20 volumio volumio[741]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 13 18:44:20 volumio volumio[741]: Error: Reference.child failed: First argument was an invalid path = "/user_data/TRDTEcHkyFeEU84D33VS43tNipm1/myPlaylists/Elektronik 7.24". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]"
Jul 13 18:44:20 volumio volumio[741]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15)
Jul 13 18:44:20 volumio volumio[741]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5)
Jul 13 18:44:20 volumio volumio[741]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17)
Jul 13 18:44:20 volumio volumio[741]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48)
Jul 13 18:44:20 volumio volumio[741]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817)
Jul 13 18:44:20 volumio volumio[741]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840)
Jul 13 18:44:20 volumio volumio[741]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337)
Jul 13 18:44:20 volumio volumio[741]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32)
Jul 13 18:44:20 volumio volumio[741]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31)
Jul 13 18:44:20 volumio volumio[741]: at /volumio/app/playlistManager.js:588:20
Jul 13 18:44:20 volumio volumio[741]: at /volumio/node_modules/jsonfile/index.js:46:5
Jul 13 18:44:20 volumio volumio[741]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16
Jul 13 18:44:20 volumio volumio[741]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3)
Jul 13 18:44:20 volumio volumio[741]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 13 18:44:20 volumio sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 18:43
Jul 13 18:44:20 volumio sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"