Aug 29 16:01:01 volumio volumio[1254]: info: CALLMETHOD: music_service ytcr showDisclaimer undefined Aug 29 16:01:01 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: ytcr , showDisclaimer Aug 29 16:01:02 volumio volumio[1254]: info: CALLMETHOD: music_service ytcr acceptDisclaimer Aug 29 16:01:02 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: ytcr , acceptDisclaimer Aug 29 16:01:03 volumio volumio[1254]: info: [yt-cast-receiver] DIAL server listening on port 8098 Aug 29 16:01:03 volumio volumio[1254]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 16:01:03 volumio volumio[1254]: info: VolumeController:: Volume=86 Mute =false Aug 29 16:01:03 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:01:03 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:01:03 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:01:03 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:01:03 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:03.113-05:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 29 16:01:03 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:03.113-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_STOPPED positionMs=659353 volume=86 Aug 29 16:01:03 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:03.114-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title= Aug 29 16:01:03 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 16:01:08 volumio volumio[1254]: info: CALLMETHOD: music_service ytcr configSaveDisclaimer [object Object] Aug 29 16:01:08 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: ytcr , configSaveDisclaimer Aug 29 16:01:44 volumio volumio[1254]: info: [yt-cast-receiver] Connecting sender through DIAL... Aug 29 16:01:45 volumio volumio[1254]: info: [yt-cast-receiver] (YouTube) Sender connected: SAMSUNG SM-A426U1 (user: Robert) Aug 29 16:01:45 volumio volumio[1254]: info: [ytcr] ***** Sender connected ***** Aug 29 16:01:45 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 16:01:51 volumio volumio[1254]: info: [yt-cast-receiver] Player.play(): m8V5PrPsLxU @ 0s Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioStop Aug 29 16:01:51 volumio volumio[1254]: info: CoreStateMachine::stop Aug 29 16:01:51 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:01:51 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 29 16:01:51 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 29 16:01:51 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 29 16:01:51 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:01:51 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:51.678-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PAUSED positionMs=0 volume=86 Aug 29 16:01:51 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:51.678-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title= Aug 29 16:01:51 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:51.680-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PAUSED positionMs=0 volume=86 Aug 29 16:01:51 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:51.680-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title= Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 29 16:01:51 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:01:51 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:01:51 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 29 16:01:51 volumio volumio[1254]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received ytcr Aug 29 16:01:51 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:51.700-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PAUSED positionMs=0 volume=86 Aug 29 16:01:51 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:51.700-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title= Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.673-05:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.686-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=13.283295ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.718-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://securetoken.googleapis.com duration=43.61418ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.729-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://functions.volumio.cloud duration=54.872821ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.729-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://functions.volumio.cloud duration=55.469126ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.749-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://google.com duration=75.816868ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.790-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://www.googleapis.com duration=116.251746ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.795-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://database.volumio.cloud duration=120.899489ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.813-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=http://pushupdates.volumio.org duration=139.103875ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.813-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=138.802879ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.864-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=189.125049ms Aug 29 16:01:57 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:57.920-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=http://plugins.volumio.org duration=246.060764ms Aug 29 16:01:58 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:58.101-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=http://cddb.volumio.org duration=427.100902ms Aug 29 16:01:58 volumio volumio5-onboarding[1655]: time=2026-08-29T16:01:58.139-05:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:43496 @ 0x1c00750" latency=55.18951ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=465.306518ms Aug 29 16:01:59 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Aug 29 16:01:59 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Aug 29 16:01:59 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Aug 29 16:01:59 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Aug 29 16:01:59 volumio volumio[1254]: info: [ytcr] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Aug 29 16:01:59 volumio volumio[1254]: info: [ytcr] Innertube support service: Start service with Node Aug 29 16:01:59 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:01:59 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:01:59 volumio volumio[1254]: info: Discovery: Getting this device information Aug 29 16:01:59 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:01:59 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:01:59 volumio volumio[1254]: verbose: New Socket.io Connection to 192.168.1.55:3000 from 192.168.1.8 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 65 Aug 29 16:01:59 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 16:01:59 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 16:02:01 volumio volumio[1254]: info: [ytcr] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":35433}} Aug 29 16:02:01 volumio volumio[1254]: info: [ytcr] Innertube support service running at http://127.0.0.1:35433 Aug 29 16:02:04 volumio volumio[1254]: info: [ytcr] Obtained session PO token using visitorData (expires in 43199 seconds) Aug 29 16:02:04 volumio volumio[1254]: info: [ytcr] Going to refresh session PO token in 43099 seconds Aug 29 16:02:04 volumio volumio[1254]: info: [ytcr] Obtained PO token for video #m8V5PrPsLxU: Mlswo2zoET4AKTDna6Q1ix23O6WRkCpsQpnlPwyXXO5pgZByxp_f7xrLHmzl5f_6rxfcB6UAS-FBTMjKCfIw5d3xV_U6bxdeect-RSns0x2qas-9C5C-K3LJjUpO Aug 29 16:02:06 volumio volumio[1254]: info: [ytcr] (m8V5PrPsLxU) fetching player data using YTMUSIC client... Aug 29 16:02:06 volumio volumio[1254]: info: [ytcr] (Let You Go) validating stream URL "https://rr3---sn-vgqsrn6r.googlevideo.com/videoplayback?expire=1788058926&ei=zkiTaoGnCOyjzN0Px6XMsQs&ip=135.135.137.14&id=o-AAmH4vxpsBaZOignn9UsiI1_CaSujlqhrTlDVRGkpeHw&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1788037326%2C&mh=CB&mm=31%2C26&mn=sn-vgqsrn6r%2Csn-2op5q5-58&ms=au%2Conr&mv=m&mvi=3&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=us&initcwndbps=3902500&hightc=yes&siu=1&bui=AR3QkAkrEfmz2jvEutRJUeKBhLd-swwHsUG59tt-FzjUb0hg4VDw0U5WTsSHpyZ2wzJaRW-BMQ&spc=KBGBcrvZdeN869AzpYrvNSOzVxfvNtM502F5rGZ4T3wyFMUQrw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1R1GdlVtVAu03HEGu1DvtLEX&rqh=1&gir=yes&clen=7586371&dur=232.401&lmt=1759963998000003&mt=1788037007&fvip=5&keepalive=yes&fexp=51565115%2C52135441&c=WEB_REMIX&sefc=1&txp=1432534&n=lC9ZGUEbSNxHKg&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=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgFg8JWoa3_2TFw6VW6AxPXBUza4k47qdGC-_RnjSFWA4CIQDyHlHmo-TPgUp_gq0ICODY1tzu2hw6i9bmIVHTC3XDwQ%3D%3D&sig=AE0s2JYwRgIhAOVN8v8rFkdu2S2Hmn76EyBKsKAvMmZEMIw6dCox-BNGAiEA-VddliOwMDQbBdyFqnfj_9q_JVQrMrf-0k5T8g6CU9o%3D&cver=1.20250219.01.00&pot=Mlswo2zoET4AKTDna6Q1ix23O6WRkCpsQpnlPwyXXO5pgZByxp_f7xrLHmzl5f_6rxfcB6UAS-FBTMjKCfIw5d3xV_U6bxdeect-RSns0x2qas-9C5C-K3LJjUpO"... Aug 29 16:02:06 volumio volumio[1254]: info: [ytcr] (Let You Go) stream validated in 0.081s. Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:06 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:07 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:07 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:07 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:07 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:07 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:07 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:07 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:02:07 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 29 16:02:07 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:02:07 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:02:07 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:02:07 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:07.490-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PLAYING positionMs=996 volume=86 Aug 29 16:02:07 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:07.491-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title="Let You Go" Aug 29 16:02:14 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 16:02:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 16:02:14 volumio volumio[1254]: Disk list : [] Aug 29 16:02:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 16:02:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 16:02:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Aug 29 16:02:14 volumio volumio[1254]: info: Received Get System Version Aug 29 16:02:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 16:02:18 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 16:02:22 volumio volumio[1254]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 29 16:02:22 volumio volumio[1254]: info: Preparing to save Alsa Options, stopping services first Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::volumioPause Aug 29 16:02:22 volumio volumio[1254]: info: CoreStateMachine::pause Aug 29 16:02:22 volumio volumio[1254]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:02:22 volumio volumio[1254]: info: CoreStateMachine::servicePause Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::servicePause Aug 29 16:02:22 volumio volumio[1254]: info: [yt-cast-receiver] Player.pause() Aug 29 16:02:22 volumio volumio[1254]: info: Saving Audio Output to: {"output_device":{"value":"4","label":"FX-AUDIO-DAC-X6"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 16:02:22 volumio volumio[1254]: info: Setting mixer PCM for card FX-AUDIO-DAC-X6 Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 16:02:22 volumio volumio[1254]: info: Updating Volume Controller Parameters: Device: 4 Name: FX-AUDIO-DAC-X6 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 16:02:22 volumio volumio[1254]: info: Disabling external Volume Control Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 16:02:22 volumio volumio[1254]: info: Preparing to generate the ALSA configuration file Aug 29 16:02:22 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:22 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:22 volumio volumio[1254]: info: Ignoring MPD Status Update Aug 29 16:02:22 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 29 16:02:23 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.007-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PAUSED positionMs=16367 volume=86 Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.008-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title="Let You Go" Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 16:02:23 volumio volumio[1254]: info: VolumeController:: Volume=35 Mute =false Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:02:23 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.066-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PAUSED positionMs=16367 volume=35 Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 29 16:02:23 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.069-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title="Let You Go" Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.078-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PAUSED positionMs=16367 volume=35 Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.078-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title="Let You Go" Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 29 16:02:23 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 29 16:02:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.104-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" state=STATUS_PAUSED positionMs=16367 volume=35 Aug 29 16:02:23 volumio volumio5-onboarding[1655]: time=2026-08-29T16:02:23.104-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:43496 @ 0x1c00750" id= title="Let You Go" Aug 29 16:02:23 volumio volumio[1254]: info: Asound.conf file written Aug 29 16:02:23 volumio sudo[3426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 29 16:02:23 volumio sudo[3426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 16:02:25 volumio sudo[3426]: pam_unix(sudo:session): session closed for user root Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: No state is present for card b1 Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: /usr/share/alsa/init/bcm2835:14: control element not found Aug 29 16:02:25 volumio volumio[1254]: No state is present for card b1 Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: No state is present for card Headphones Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: /usr/share/alsa/init/bcm2835:20: control element not found Aug 29 16:02:25 volumio volumio[1254]: No state is present for card Headphones Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: No state is present for card vc4hdmi0 Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 29 16:02:25 volumio volumio[1254]: Hardware is initialized using a generic method Aug 29 16:02:25 volumio volumio[1254]: No state is present for card vc4hdmi0 Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: No state is present for card vc4hdmi1 Aug 29 16:02:25 volumio volumio[1254]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 29 16:02:25 volumio volumio[1254]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 29 16:02:25 volumio volumio[1254]: Hardware is initialized using a generic method Aug 29 16:02:25 volumio volumio[1254]: No state is present for card vc4hdmi1 Aug 29 16:02:25 volumio volumio[1254]: No state is present for card FXAUDIODACX6 Aug 29 16:02:25 volumio volumio[1254]: Found hardware: "USB-Audio" "USB Mixer" "USB040d:340b" "" "" Aug 29 16:02:25 volumio volumio[1254]: Hardware is initialized using a generic method Aug 29 16:02:25 volumio volumio[1254]: No state is present for card FXAUDIODACX6 Aug 29 16:02:25 volumio volumio[1254]: No state is present for card Device Aug 29 16:02:25 volumio volumio[1254]: Found hardware: "USB-Audio" "USB Mixer" "USB4842:0001" "" "" Aug 29 16:02:25 volumio volumio[1254]: Hardware is initialized using a generic method Aug 29 16:02:25 volumio volumio[1254]: No state is present for card Device Aug 29 16:02:25 volumio volumio[1254]: info: Output device has changed, restarting MPD Aug 29 16:02:25 volumio volumio[1254]: info: Output device has changed, restarting Shairport Sync Aug 29 16:02:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:02:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:02:25 volumio sudo[3432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 16:02:25 volumio sudo[3432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 16:02:25 volumio sudo[3432]: pam_unix(sudo:session): session closed for user root Aug 29 16:02:25 volumio sudo[3434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 16:02:25 volumio sudo[3434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 16:02:25 volumio volumio[1254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 16:02:25 volumio volumio[1254]: info: Output device has changed, restarting MPD Aug 29 16:02:25 volumio volumio[1254]: info: Output device has changed, restarting Shairport Sync Aug 29 16:02:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:02:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:02:25 volumio sudo[3442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 16:02:25 volumio sudo[3442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 16:02:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 16:02:25 volumio sudo[3442]: pam_unix(sudo:session): session closed for user root Aug 29 16:02:25 volumio volumio[1254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 16:02:25 volumio sudo[3444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 16:02:25 volumio sudo[3444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 16:02:25 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 29 16:02:25 volumio volumio[1254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:02:25 volumio volumio[1254]: MPDError: Not connected Aug 29 16:02:25 volumio volumio[1254]: at MPDClient.send (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:101:13) Aug 29 16:02:25 volumio volumio[1254]: at MPDClient.sendCommand (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:65:10) Aug 29 16:02:25 volumio volumio[1254]: at Object.get (/data/plugins/music_service/ytcr/node_modules/mpd-api/lib/api/index.js:60:16) Aug 29 16:02:25 volumio volumio[1254]: at MPDPlayer.getVolumioState (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:303:100) Aug 29 16:02:25 volumio volumio[1254]: at ControllerYTCR.pushState (/data/plugins/music_service/ytcr/dist/index.js:567:104) Aug 29 16:02:25 volumio volumio[1254]: at /data/plugins/music_service/ytcr/dist/index.js:338:36 Aug 29 16:02:25 volumio volumio[1254]: at MPDPlayer. (/data/plugins/music_service/ytcr/dist/index.js:341:15) Aug 29 16:02:25 volumio volumio[1254]: at MPDPlayer.emit (node:events:526:35) Aug 29 16:02:25 volumio volumio[1254]: at MPDPlayer._Player_setStatusAndEmit (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/Player.js:334:10) Aug 29 16:02:25 volumio volumio[1254]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 29 16:02:25 volumio volumio[1254]: at async /data/plugins/music_service/ytcr/dist/index.js:312:17 { Aug 29 16:02:25 volumio volumio[1254]: code: 'ENOTCONNECTED' Aug 29 16:02:25 volumio volumio[1254]: } Aug 29 16:02:25 volumio volumio[1254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:02:27 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 16:02:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 16:02:27 volumio systemd[1]: mpd.service: Consumed 4.146s CPU time. Aug 29 16:02:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 16:02:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 16:02:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 16:02:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 16:02:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 16:02:27 volumio sudo[3484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 16:01' Aug 29 16:02:27 volumio sudo[3484]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"