Feb 10 15:46:00 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 10 15:46:00 volumio volumio[2271046]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 10 15:46:00 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 10 15:46:00 volumio volumio[2271046]: info: Received Get System Version Feb 10 15:46:00 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 15:46:00 volumio volumio[2271046]: info: Received Get System Info Feb 10 15:46:00 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 15:46:00 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 15:46:00 volumio volumio[2271046]: info: Discovery: Getting this device information Feb 10 15:46:00 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:46:00 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 15:46:04 volumio volumio[2271046]: info: [ytcr] (Gfikhqr-M84) fetching player data using YTMUSIC client... Feb 10 15:46:05 volumio volumio[2271046]: info: [ytcr] (Sad But True) validating stream URL "https://rr2---sn-j5caxoxu-hh0k.googlevideo.com/videoplayback?expire=1770777964&ei=DJmLaaagM4i2kucP8aTpiQc&ip=67.85.91.3&id=o-ABm9kE9qDD_E3cb6uYQoeiEP-LhRojbDJhLsUQb45JVU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1770756364%2C&mh=VX&mm=31%2C29&mn=sn-j5caxoxu-hh0k%2Csn-ab5l6ny7&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&gcr=us&initcwndbps=4507500&siu=1&bui=AW-iu_rMjfDI6Q3UAYW_Uy960GDjbiNAIfUQTWuOyfJZOYeWrvaoAgxpvtsIAZrF3G2xjVoUGg&spc=q5xjPGOlAqHEBBFwlHHZoJhyVELcVyg9vIY7zlNk8ZNx5HN8kQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=JH_quirLyVhZkW5bnhWfoOcS&rqh=1&gir=yes&clen=5045138&dur=324.581&lmt=1765017885906905&mt=1770755850&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=2W9sU0jW2heahQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgQTe8vwQo3m5XPBYE9Y10GdBsfXEiiTEAX7p_w5aN7nICIF_3KzmoyhjPIJ17QkpC09GtlLDYNvvjXfYwKm8dcu7D&sig=AJEij0EwRQIhAKVVzusHqE1tdE5JE47UoIIpqzV3inwQq2RET5A91XC8AiABaxQHZlTyZYXmEib_sMOjYPaFj6C7AVjRy4aS9pLrHA%3D%3D&pot=MlVAw2UJ00b9tED9qnCc4cxsPcqPYdq2AR26f8sHY3oukTZTvcISxKY1YZDWemIFT16dZ0_4K44ZJNx7fISubX248eWQYnQbHu-_ZIbD4dTYsbsmff7e&cver=1.20250219.01.00"... Feb 10 15:46:05 volumio volumio[2271046]: info: [ytcr] (Sad But True) stream validated in 0.057s. Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:05 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:07 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:46:07 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:46:07 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:46:07 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:46:08 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:46:08 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:46:09 volumio go-librespot[2271607]: time="2026-02-10T15:46:09-05:00" level=trace msg="received accesspoint ping" Feb 10 15:46:09 volumio go-librespot[2271607]: time="2026-02-10T15:46:09-05:00" level=trace msg="received accesspoint pong ack" Feb 10 15:46:10 volumio go-librespot[2271607]: time="2026-02-10T15:46:10-05:00" level=trace msg="sent dealer ping" Feb 10 15:46:10 volumio go-librespot[2271607]: time="2026-02-10T15:46:10-05:00" level=trace msg="received dealer pong" Feb 10 15:46:11 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:46:11 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:46:12 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:46:12 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:46:12 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:46:12 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:46:12 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:46:12 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:46:12 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:46:12 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:46:13 volumio volumio[2271046]: warn: [yt-cast-receiver] DefaultPlaylistRequestHandler failed to mark video Gfikhqr-M84 as watched: Feb 10 15:46:13 volumio volumio[2271046]: (Error) No playback tracking URL found Feb 10 15:46:13 volumio volumio[2271046]: Error: No playback tracking URL found Feb 10 15:46:13 volumio volumio[2271046]: at DefaultPlaylistRequestHandler.markWatched (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:185:19) Feb 10 15:46:13 volumio volumio[2271046]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 10 15:46:13 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideos (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:96:9) Feb 10 15:46:13 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideosAbortable (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/PlaylistRequestHandler.js:36:24) Feb 10 15:46:13 volumio volumio[2271046]: at async Playlist._Playlist_refreshPreviousNext (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:314:23) Feb 10 15:46:13 volumio volumio[2271046]: at async Playlist.next (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:237:9) Feb 10 15:46:13 volumio volumio[2271046]: at async MPDPlayer._MPDPlayer_handleExternalMPDEvent (/data/plugins/music_service/ytcr/dist/lib/MPDPlayer.js:574:29) Feb 10 15:46:13 volumio volumio[2271046]: at async /data/plugins/music_service/ytcr/dist/lib/MPDSubsystemEventEmitter.js:135:25 Feb 10 15:46:13 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:46:13 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:46:13 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:46:13 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:46:13 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:13 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:46:13 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:13 volumio volumio[2271046]: info: [LastFM] Current track has sufficient metadata: title (Sad But True) and artist (Metallica) passed on explicitly Feb 10 15:46:13 volumio volumio[2271046]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Feb 10 15:46:13 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:14 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Feb 10 15:46:20 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:46:20 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:46:21 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:46:21 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:46:21 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:46:21 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:46:30 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 15:46:30 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 10 15:46:35 volumio volumio[2271046]: info: VolumeController::SetAlsaVolume55 Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: SPOTIFY VOLUME 50 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: VOLUMIO VOLUME 55 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 10 15:46:35 volumio volumio[2271046]: info: Setting Spotify Volume from Volumio: 55 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 10 15:46:35 volumio volumio[2271046]: info: VolumeController::SetAlsaVolume75 Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: SPOTIFY VOLUME 55 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: VOLUMIO VOLUME 75 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 10 15:46:35 volumio volumio[2271046]: info: Setting Spotify Volume from Volumio: 75 Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:46:35 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:46:35 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:46:35 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:46:37 volumio volumio[2271046]: SPOTIFY: SETTING SPOTIFY VOLUME 75 Feb 10 15:46:37 volumio volumio[2271046]: info: Sending Spotify command with payload to local API: /player/volume Feb 10 15:46:37 volumio go-librespot[2271607]: time="2026-02-10T15:46:37-05:00" level=debug msg="update volume requested to 49151/65535" Feb 10 15:46:37 volumio go-librespot[2271607]: time="2026-02-10T15:46:37-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 10 15:46:37 volumio go-librespot[2271607]: time="2026-02-10T15:46:37-05:00" level=trace msg="emitting websocket event: volume" Feb 10 15:46:37 volumio volumio[2271046]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Feb 10 15:46:37 volumio volumio[2271046]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Feb 10 15:46:40 volumio go-librespot[2271607]: time="2026-02-10T15:46:40-05:00" level=trace msg="sent dealer ping" Feb 10 15:46:40 volumio go-librespot[2271607]: time="2026-02-10T15:46:40-05:00" level=trace msg="received dealer pong" Feb 10 15:46:45 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 15:46:45 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 10 15:46:46 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:46:50 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:46:50 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:46:51 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:46:51 volumio volumio[2271046]: info: FusionDsp - else 1 -2.5 Feb 10 15:46:51 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:46:51 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:46:51 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:46:55 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:46:55 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:46:56 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:46:56 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:46:56 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:46:56 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:46:58 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:46:58 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:46:59 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:46:59 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:47:00 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:47:00 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::volumioSeek Feb 10 15:47:07 volumio volumio[2271046]: info: CoreStateMachine::seek Feb 10 15:47:07 volumio volumio[2271046]: info: [yt-cast-receiver] Player.seek(): 57s Feb 10 15:47:07 volumio volumio[2271046]: info: CoreStateMachine::startPlaybackTimer Feb 10 15:47:07 volumio volumio[2271046]: info: CorePlayQueue::getTrack 0 Feb 10 15:47:07 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:07 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:07 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:07 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:07 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:07 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:07 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:07 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:07 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:07 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:07 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:07 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:07 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:07 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:08 volumio volumio[2271046]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Feb 10 15:47:08 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Feb 10 15:47:09 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:47:09 volumio volumio[2271046]: info: FusionDsp - Sent ResetClippedSamples command Feb 10 15:47:10 volumio volumio[2271046]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 15:47:10 volumio volumio[2271046]: info: CoreCommandRouter::Close All Modals sent Feb 10 15:47:10 volumio go-librespot[2271607]: time="2026-02-10T15:47:10-05:00" level=trace msg="sent dealer ping" Feb 10 15:47:10 volumio go-librespot[2271607]: time="2026-02-10T15:47:10-05:00" level=trace msg="received dealer pong" Feb 10 15:47:11 volumio volumio[2271046]: info: CoreCommandRouter::volumioNext Feb 10 15:47:11 volumio volumio[2271046]: info: CoreStateMachine::next Feb 10 15:47:11 volumio volumio[2271046]: info: [yt-cast-receiver] Player.next() Feb 10 15:47:11 volumio volumio[2271046]: warn: [yt-cast-receiver] DefaultPlaylistRequestHandler failed to mark video 9PmfpsIh5w0 as watched: Feb 10 15:47:11 volumio volumio[2271046]: (Error) No playback tracking URL found Feb 10 15:47:11 volumio volumio[2271046]: Error: No playback tracking URL found Feb 10 15:47:11 volumio volumio[2271046]: at DefaultPlaylistRequestHandler.markWatched (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:185:19) Feb 10 15:47:11 volumio volumio[2271046]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 10 15:47:11 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideos (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:96:9) Feb 10 15:47:11 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideosAbortable (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/PlaylistRequestHandler.js:36:24) Feb 10 15:47:11 volumio volumio[2271046]: at async Playlist._Playlist_refreshPreviousNext (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:314:23) Feb 10 15:47:11 volumio volumio[2271046]: at async Playlist.next (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:237:9) Feb 10 15:47:11 volumio volumio[2271046]: at async MPDPlayer.next (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/Player.js:164:27) Feb 10 15:47:12 volumio volumio[2271046]: info: CoreCommandRouter::volumioNext Feb 10 15:47:12 volumio volumio[2271046]: info: CoreStateMachine::next Feb 10 15:47:12 volumio volumio[2271046]: info: [yt-cast-receiver] Player.stop() Feb 10 15:47:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:12 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:12 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:12 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:12 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:12 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:12 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:12 volumio volumio[2271046]: info: [yt-cast-receiver] Player.play(): 9PmfpsIh5w0 @ 0s Feb 10 15:47:12 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:12 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:12 volumio volumio[2271046]: info: FusionDsp - Volumio is not playing Feb 10 15:47:12 volumio volumio[2271046]: info: FusionDsp - Clipped samples monitor stopped Feb 10 15:47:12 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:13 volumio volumio[2271046]: info: [ytcr] (9PmfpsIh5w0) fetching player data using YTMUSIC client... Feb 10 15:47:13 volumio volumio[2271046]: info: [ytcr] (Whiskey In The Jar) validating stream URL "https://rr3---sn-j5caxoxu-hh0d.googlevideo.com/videoplayback?expire=1770778033&ei=UZmLabadFriKr9gP7L6vuQQ&ip=67.85.91.3&id=o-AP2ci8I12zg7A6LP4IpAdYQZBhU_rB4sVHThcp49g6Nk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1770756433%2C&mh=FC&mm=31%2C29&mn=sn-j5caxoxu-hh0d%2Csn-ab5l6ny7&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=21&rms=au%2Cau&gcr=us&initcwndbps=4392500&siu=1&bui=AW-iu_p9uaaTctGkouOKAcAx50uwRon4bj6f8Znkow00PaNL_vQfHNMJtcBfa5EYDs6yrk9eTA&spc=q5xjPPQqrs3Y4mUU0RMl5dLSLa8LAH2m5SrVP9BHuzX8CDoBag&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ZoBM8ejWRHfmAEEDVEQDYEUS&rqh=1&gir=yes&clen=4670825&dur=304.901&lmt=1762322867872726&mt=1770756076&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=dihNAfHGmAzC1Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgcPYrWln2PrZ9_Blj1VGVyxlMN-NF81vk18g8PRaCsEsCIQC193X9JoaBWHjiUDcGiGoIas3PSjqpjw98bencB4aKBA%3D%3D&sig=AJEij0EwRAIgD-3uFVbgU_r1QS9RhbOux10dcx2Hyic7RNFX512oqbACIGdFiN9Rg07McsAfKHro8d6vG-bVkn-DcEKQwn7LtN4U&pot=MlWl1TXkwxj8XKXr-p2Mv82E2NzfjMroAPVfaZvqcyQvedNF7S8CmqfdhIaGl3JbTrZ4cR8VO9AYzDltLGm-M3xQFPPAj2RFHwedvxwmgByE4jzm0_Jw&cver=1.20250219.01.00"... Feb 10 15:47:13 volumio volumio[2271046]: info: [ytcr] (Whiskey In The Jar) stream validated in 0.043s. Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:13 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Feb 10 15:47:14 volumio volumio[2271046]: info: FusionDsp - ---- read samplerate from file: 48000 Feb 10 15:47:14 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:14 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:14 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:14 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:14 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:14 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:14 volumio volumio[2271046]: info: [LastFM] Current track has sufficient metadata: title (Whiskey In The Jar) and artist (Metallica) passed on explicitly Feb 10 15:47:14 volumio volumio[2271046]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Feb 10 15:47:14 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:14 volumio volumio[2271046]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Feb 10 15:47:14 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:14 volumio volumio[2271046]: info: FusionDsp - Clipping Monitor started Feb 10 15:47:21 volumio volumio[2271046]: warn: [yt-cast-receiver] DefaultPlaylistRequestHandler failed to mark video mPf4v9LGF30 as watched: Feb 10 15:47:21 volumio volumio[2271046]: (Error) No playback tracking URL found Feb 10 15:47:21 volumio volumio[2271046]: Error: No playback tracking URL found Feb 10 15:47:21 volumio volumio[2271046]: at DefaultPlaylistRequestHandler.markWatched (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:185:19) Feb 10 15:47:21 volumio volumio[2271046]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 10 15:47:21 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideos (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:96:9) Feb 10 15:47:21 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideosAbortable (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/PlaylistRequestHandler.js:36:24) Feb 10 15:47:21 volumio volumio[2271046]: at async Playlist._Playlist_refreshPreviousNext (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:314:23) Feb 10 15:47:21 volumio volumio[2271046]: at async Playlist.updateByMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:134:9) Feb 10 15:47:21 volumio volumio[2271046]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:403:13) Feb 10 15:47:21 volumio volumio[2271046]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:302:13) Feb 10 15:47:22 volumio volumio[2271046]: info: [yt-cast-receiver] Player.stop() Feb 10 15:47:22 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:22 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:22 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:22 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:22 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:22 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:22 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:22 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:22 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:22 volumio volumio[2271046]: info: [yt-cast-receiver] Player.play(): mPf4v9LGF30 @ 0s Feb 10 15:47:22 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:22 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:22 volumio volumio[2271046]: info: FusionDsp - Volumio is not playing Feb 10 15:47:22 volumio volumio[2271046]: info: FusionDsp - Clipped samples monitor stopped Feb 10 15:47:22 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:22 volumio volumio[2271046]: info: [ytcr] (mPf4v9LGF30) fetching player data using YTMUSIC client... Feb 10 15:47:23 volumio volumio[2271046]: info: [ytcr] (Wherever I May Roam) validating stream URL "https://rr6---sn-j5caxoxu-hh0k.googlevideo.com/videoplayback?expire=1770778043&ei=WpmLaZ3-PKeHkucPvo-joAs&ip=67.85.91.3&id=o-ADnK5dYfK8LbgB2XAIk4MD4dNoCdGg6e69f3XnHZftvu&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1770756443%2C&mh=N9&mm=31%2C29&mn=sn-j5caxoxu-hh0k%2Csn-ab5sznzy&ms=au%2Crdu&mv=m&mvi=6&pl=21&rms=au%2Cau&gcr=us&initcwndbps=4422500&siu=1&bui=AW-iu_phSzKpc2LYNIRmdO11Y33ZPNJ41ix1b_Gg0sF-VUZQzXdWMEFfJvUCNPEbMJmChV-OHA&spc=q5xjPECHOhEAciUChSPm8lyxafbChHVnGwu3Bl3Vkzd4vF7_JQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=X0XZoSKaI6yztxU-QPZSELsS&rqh=1&gir=yes&clen=6380190&dur=404.261&lmt=1762207712393575&mt=1770756076&fvip=3&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=Rh0bBj0e7kdXmg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAL72MYYoFzeHQJtoJN1PaGpu2jacXQeXpqZ37Uo3KHfCAiAfKpUxN0qTmla_MDbimmP0buj0JESqRWw0vjT6sGPwaw%3D%3D&sig=AJEij0EwRQIgGI9VGhT1dDziOunUo5NPGO-35rwoZXjP8S60-TH3L3gCIQCTnCfE78tbgWvLJzkd5BvDgaVOBwwX4w4bq293DfRh4g%3D%3D&pot=MlUmi6b25EXFNia1aY-r4vTuW4JMnu21OZ_cNwj4VHkWE1Abfj0lx563B9gVhVUGd9z7L4wHHI0hprozv3uZbkU6l61TnUMYJm0yFFpPaQlW8hJm9fDM&cver=1.20250219.01.00"... Feb 10 15:47:23 volumio volumio[2271046]: info: [ytcr] (Wherever I May Roam) stream validated in 0.061s. Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:23 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Feb 10 15:47:24 volumio volumio[2271046]: info: FusionDsp - ---- read samplerate from file: 48000 Feb 10 15:47:24 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:24 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:24 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:24 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:24 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:24 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:24 volumio volumio[2271046]: info: [LastFM] Current track has sufficient metadata: title (Wherever I May Roam) and artist (Metallica) passed on explicitly Feb 10 15:47:24 volumio volumio[2271046]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Feb 10 15:47:24 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:24 volumio volumio[2271046]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Feb 10 15:47:24 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:24 volumio volumio[2271046]: info: FusionDsp - Clipping Monitor started Feb 10 15:47:25 volumio volumio[2271046]: info: [yt-cast-receiver] Player.seek(): 123s Feb 10 15:47:25 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:25 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:25 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:25 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:25 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:25 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:25 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:26 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:26 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:26 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:26 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:26 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:26 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:26 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:26 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:26 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:26 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:26 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:31 volumio volumio[2271046]: info: [yt-cast-receiver] Player.seek(): 0s Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:31 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:31 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:31 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:31 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:31 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:31 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:31 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:31 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:31 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:31 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:31 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:36 volumio volumio[2271046]: warn: [yt-cast-receiver] DefaultPlaylistRequestHandler failed to mark video UXzkX6xapAQ as watched: Feb 10 15:47:36 volumio volumio[2271046]: (Error) No playback tracking URL found Feb 10 15:47:36 volumio volumio[2271046]: Error: No playback tracking URL found Feb 10 15:47:36 volumio volumio[2271046]: at DefaultPlaylistRequestHandler.markWatched (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:185:19) Feb 10 15:47:36 volumio volumio[2271046]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 10 15:47:36 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideos (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:96:9) Feb 10 15:47:36 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideosAbortable (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/PlaylistRequestHandler.js:36:24) Feb 10 15:47:36 volumio volumio[2271046]: at async Playlist._Playlist_refreshPreviousNext (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:314:23) Feb 10 15:47:36 volumio volumio[2271046]: at async Playlist.updateByMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:134:9) Feb 10 15:47:36 volumio volumio[2271046]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:403:13) Feb 10 15:47:36 volumio volumio[2271046]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:302:13) Feb 10 15:47:36 volumio volumio[2271046]: warn: [yt-cast-receiver] DefaultPlaylistRequestHandler failed to mark video UXzkX6xapAQ as watched: Feb 10 15:47:36 volumio volumio[2271046]: (Error) No playback tracking URL found Feb 10 15:47:36 volumio volumio[2271046]: Error: No playback tracking URL found Feb 10 15:47:36 volumio volumio[2271046]: at DefaultPlaylistRequestHandler.markWatched (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:185:19) Feb 10 15:47:36 volumio volumio[2271046]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 10 15:47:36 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideos (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/DefaultPlaylistRequestHandler.js:96:9) Feb 10 15:47:36 volumio volumio[2271046]: at async DefaultPlaylistRequestHandler.getPreviousNextVideosAbortable (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/PlaylistRequestHandler.js:36:24) Feb 10 15:47:36 volumio volumio[2271046]: at async Playlist._Playlist_refreshPreviousNext (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:314:23) Feb 10 15:47:36 volumio volumio[2271046]: at async Playlist.updateByMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Playlist.js:134:9) Feb 10 15:47:36 volumio volumio[2271046]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:403:13) Feb 10 15:47:36 volumio volumio[2271046]: at async YouTubeApp._YouTubeApp_handleIncomingMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:302:13) Feb 10 15:47:37 volumio volumio[2271046]: info: [yt-cast-receiver] Player.stop() Feb 10 15:47:37 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:37 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:37 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:37 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:37 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:37 volumio volumio[2271046]: info: [yt-cast-receiver] Player.play(): UXzkX6xapAQ @ 0s Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:37 volumio volumio[2271046]: info: FusionDsp - Volumio is not playing Feb 10 15:47:37 volumio volumio[2271046]: info: FusionDsp - Clipped samples monitor stopped Feb 10 15:47:37 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:37 volumio volumio[2271046]: info: [yt-cast-receiver] Player.resume() Feb 10 15:47:37 volumio volumio[2271046]: info: [yt-cast-receiver] Player.play(): UXzkX6xapAQ @ 0s Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:37 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:38 volumio volumio[2271046]: info: [ytcr] (UXzkX6xapAQ) fetching player data using YTMUSIC client... Feb 10 15:47:38 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:38 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:38 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:38 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:38 volumio volumio[2271046]: info: FusionDsp - Volumio is not playing Feb 10 15:47:38 volumio volumio[2271046]: info: FusionDsp - Clipped samples monitor stopped Feb 10 15:47:38 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Feb 10 15:47:38 volumio volumio[2271046]: info: [ytcr] (Peaches & Eggplants (feat. 21 Savage)) validating stream URL "https://rr4---sn-j5caxoxu-hh0d.googlevideo.com/videoplayback?expire=1770778058&ei=apmLafH3Bt-Gr9gPvKTisQQ&ip=67.85.91.3&id=o-AB60ZnrpxFkJl0Gu4aOaqk7xHuBF53_HfdC5kkHFmdMU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1770756458%2C&mh=6X&mm=31%2C29&mn=sn-j5caxoxu-hh0d%2Csn-ab5l6nrk&ms=au%2Crdu&mv=m&mvi=4&pl=21&rms=au%2Cau&gcr=us&initcwndbps=4392500&siu=1&bui=AW-iu_qkQOpzmB1zp6YBDelA_ektJEpomcVl-O0z-2JlUitJSlQc-0KrGvq_lPMYBnZHlO1MJQ&spc=q5xjPMVTert1PkBniDJgRkvQ5_yGTPNoCzuOl9GKaMy-NIb3bg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=WyEAQ2bcaL71oZwnlG6mc3cS&rqh=1&gir=yes&clen=3485248&dur=203.821&lmt=1762143968449470&mt=1770756076&fvip=3&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=5532534&n=JsLDrXS_ZW1jng&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgN3L6PTtvwCgmwdBXeDHUHoiPMk6jTMVLcBUN4iuqMaICIEQzFG9GxzoHvHL5t3avp3duzg8_i1_8IdJPxP09Hf2O&sig=AJEij0EwRAIgf9gghsFKcRkntXXxDQgh_J3QJZVBg08zFldDl8CFVEECIEUYN2zuUkKpt_wHwgAWvi7RVCURwo6RVp-vKnuv48l8&pot=MlWqYVDoTvGHpqpfn5EBVrZ-12i6gEcBew9Q3f7m_s1Ug9zxiCOPc9wnizLjm_-yNUx3xXoZtjljNjbZSWUz2geqG0elg-msZP2-6a2uK87yxYW1vsKe&cver=1.20250219.01.00"... Feb 10 15:47:38 volumio volumio[2271046]: info: [ytcr] (Peaches & Eggplants (feat. 21 Savage)) stream validated in 0.041s. Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:38 volumio volumio[2271046]: info: [yt-cast-receiver] Player.setVolume(): Feb 10 15:47:38 volumio volumio[2271046]: { level: 78, muted: false } Feb 10 15:47:38 volumio volumio[2271046]: info: [yt-cast-receiver] Player.setVolume(): Feb 10 15:47:38 volumio volumio[2271046]: { level: 78, muted: false } Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Feb 10 15:47:39 volumio volumio[2271046]: info: FusionDsp - ---- read samplerate from file: 48000 Feb 10 15:47:39 volumio volumio[2271046]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: Ignoring MPD Status Update Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:39 volumio volumio[2271046]: info: VolumeController::SetAlsaVolume78 Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:39 volumio volumio[2271046]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Feb 10 15:47:39 volumio volumio[2271046]: info: FusionDsp - Volumio is not playing Feb 10 15:47:39 volumio volumio[2271046]: info: FusionDsp - Clipped samples monitor stopped Feb 10 15:47:39 volumio volumio[2271046]: info: FusionDsp - Volumio is playing Feb 10 15:47:39 volumio volumio[2271046]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Feb 10 15:47:39 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Feb 10 15:47:39 volumio volumio[2271046]: SPOTIFY: SPOTIFY VOLUME 75 Feb 10 15:47:39 volumio volumio[2271046]: SPOTIFY: VOLUMIO VOLUME 78 Feb 10 15:47:39 volumio volumio[2271046]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 10 15:47:39 volumio volumio[2271046]: info: Setting Spotify Volume from Volumio: 78 Feb 10 15:47:39 volumio volumio[2271046]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::servicePushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreStateMachine::pushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioPushState Feb 10 15:47:39 volumio volumio[2271046]: info: CoreCommandRouter::volumioGetState Feb 10 15:47:39 volumio volumio[2271046]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 15:47:39 volumio volumio[2271046]: TypeError: Cannot read properties of undefined (reading 'indexOf') Feb 10 15:47:39 volumio volumio[2271046]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Feb 10 15:47:39 volumio volumio[2271046]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Feb 10 15:47:39 volumio volumio[2271046]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Feb 10 15:47:39 volumio volumio[2271046]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Feb 10 15:47:39 volumio volumio[2271046]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Feb 10 15:47:39 volumio volumio[2271046]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Feb 10 15:47:39 volumio volumio[2271046]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Feb 10 15:47:39 volumio volumio[2271046]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Feb 10 15:47:39 volumio volumio[2271046]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Feb 10 15:47:39 volumio volumio[2271046]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Feb 10 15:47:39 volumio volumio[2271046]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Feb 10 15:47:39 volumio volumio[2271046]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Feb 10 15:47:39 volumio volumio[2271046]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Feb 10 15:47:39 volumio volumio[2271046]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Feb 10 15:47:39 volumio volumio[2271046]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Feb 10 15:47:39 volumio volumio[2271046]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Feb 10 15:47:39 volumio volumio[2271046]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 15:47:39 volumio sudo[2641611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 15:46' Feb 10 15:47:39 volumio sudo[2641611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"