Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:24 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:24 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:24 volumio7443 volumio[1151]: verbose: New Socket.io Connection to 192.168.2.139:3000 from 192.168.2.213 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 16:58:24 volumio7443 volumio[1151]: verbose: New Socket.io Connection to 192.168.2.139:3000 from 192.168.2.213 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:24 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:24 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:24 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:35 volumio7443 sudo[101966]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 16:58:35 volumio7443 sudo[101966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 16:58:35 volumio7443 sudo[101966]: pam_unix(sudo:session): session closed for user root Mar 29 16:58:35 volumio7443 sudo[101968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 16:58:35 volumio7443 sudo[101968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 16:58:35 volumio7443 sudo[101968]: pam_unix(sudo:session): session closed for user root Mar 29 16:58:35 volumio7443 volumio[1151]: verbose: New Socket.io Connection to 192.168.2.139 from 192.168.2.226 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:36 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 16:58:36 volumio7443 volumio[1151]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 16:58:36 volumio7443 volumio[1151]: info: Received Get System Info Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:36 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:36 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:36 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:36 volumio7443 volumio[1151]: info: Listing playlists Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 16:58:36 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:36 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:37 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 16:58:37 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 16:58:37 volumio7443 volumio[1151]: info: Received Get System Info Mar 29 16:58:37 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:37 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:37 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:37 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:37 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:37 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:39 volumio7443 volumio[1151]: info: Executing endpoint metavolumio Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 16:58:39 volumio7443 volumio[1151]: info: Executing endpoint metavolumio Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 16:58:39 volumio7443 volumio[1151]: info: Executing endpoint metavolumio Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetQueue Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreStateMachine::getQueue Mar 29 16:58:39 volumio7443 volumio[1151]: info: CorePlayQueue::getQueue Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 16:58:39 volumio7443 volumio[1151]: info: Received Get System Info Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:39 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:39 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:39 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:45 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Mar 29 16:58:45 volumio7443 volumio[1151]: info: [jellyfin-browse] browseUri: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d Mar 29 16:58:45 volumio7443 volumio[1151]: info: [jellyfin-conn] Using previously assigned device Id for JELL-7443@DELL-2025: b7c0e2ce-476c-4371-85d6-869849280608 Mar 29 16:58:45 volumio7443 volumio[1151]: info: [jellyfin-conn] Login successful: JELL-7443@DELL-2025 Mar 29 16:58:45 volumio7443 volumio[1151]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"9df468e6f8704f539491d29fbb94e22a","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"a2e45360644bbe9e129101febdc6c19d","startIndex":0,"fields":["MediaSources"],"includeItemTypes":["Audio"]} Mar 29 16:58:45 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 16:58:45 volumio7443 volumio[1151]: verbose: [jellyfin] getItemsFromAPI(): http://p456ad.asuscomm.com:8096/Items?userId=9df468e6f8704f539491d29fbb94e22a&startIndex=0&recursive=true&parentId=a2e45360644bbe9e129101febdc6c19d&fields=MediaSources&includeItemTypes=Audio&imageTypeLimit=1&enableImageTypes=Primary Mar 29 16:58:46 volumio7443 volumio[1151]: info: Preload queue cleared Mar 29 16:58:46 volumio7443 volumio[1151]: info: Preloading song: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=8368657bfc278b6929b9b88559ca23b5 Mar 29 16:58:46 volumio7443 volumio[1151]: info: Preloading song: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=950e05f39b1c2bd0de5c228d24ba5dcf Mar 29 16:58:46 volumio7443 volumio[1151]: info: Preloading song: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=7d3c3b83eaed2b9b0381fbd88fecdc53 Mar 29 16:58:46 volumio7443 volumio[1151]: info: Preloading song: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=81544f7f74844a6464c9eee8e83bd0bc Mar 29 16:58:46 volumio7443 volumio[1151]: info: Exploding uri jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=8368657bfc278b6929b9b88559ca23b5 in service jellyfin Mar 29 16:58:46 volumio7443 volumio[1151]: info: [jellyfin-browse] explodeUri: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=8368657bfc278b6929b9b88559ca23b5 Mar 29 16:58:46 volumio7443 volumio[1151]: info: Executing endpoint getSimilarAlbums Mar 29 16:58:46 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 29 16:58:46 volumio7443 volumio[1151]: info: Exploding uri jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=950e05f39b1c2bd0de5c228d24ba5dcf in service jellyfin Mar 29 16:58:46 volumio7443 volumio[1151]: info: [jellyfin-browse] explodeUri: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=950e05f39b1c2bd0de5c228d24ba5dcf Mar 29 16:58:46 volumio7443 volumio[1151]: info: Exploding uri jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=7d3c3b83eaed2b9b0381fbd88fecdc53 in service jellyfin Mar 29 16:58:46 volumio7443 volumio[1151]: info: [jellyfin-browse] explodeUri: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=7d3c3b83eaed2b9b0381fbd88fecdc53 Mar 29 16:58:46 volumio7443 volumio[1151]: info: Executing endpoint metavolumio Mar 29 16:58:46 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 16:58:46 volumio7443 volumio[1151]: info: Exploding uri jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=81544f7f74844a6464c9eee8e83bd0bc in service jellyfin Mar 29 16:58:46 volumio7443 volumio[1151]: info: [jellyfin-browse] explodeUri: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d/song@songId=81544f7f74844a6464c9eee8e83bd0bc Mar 29 16:58:46 volumio7443 volumio[1151]: info: Executing endpoint metavolumio Mar 29 16:58:46 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 29 16:58:47 volumio7443 volumio[1151]: verbose: New Socket.io Connection to 192.168.2.139:3000 from 192.168.2.213 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:47 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:47 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:47 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:47 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:47 volumio7443 volumio[1151]: verbose: New Socket.io Connection to 192.168.2.139:3000 from 192.168.2.213 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 16:58:47 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 16:58:49 volumio7443 volumio[1151]: info: Preload queue cleared Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::ClearQueue Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::stop Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::clearPlayQueue Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::saveQueue Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushQueue Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::addQueueItems Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::addQueueItems Mar 29 16:58:49 volumio7443 volumio[1151]: info: Preload queue cleared Mar 29 16:58:49 volumio7443 volumio[1151]: info: Adding Item to queue: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d Mar 29 16:58:49 volumio7443 volumio[1151]: info: Exploding uri jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d in service jellyfin Mar 29 16:58:49 volumio7443 volumio[1151]: info: [jellyfin-browse] explodeUri: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d Mar 29 16:58:49 volumio7443 volumio[1151]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"9df468e6f8704f539491d29fbb94e22a","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"a2e45360644bbe9e129101febdc6c19d","startIndex":0,"fields":["MediaSources"],"includeItemTypes":["Audio"]} Mar 29 16:58:49 volumio7443 volumio[1151]: verbose: [jellyfin] getItemsFromAPI(): http://p456ad.asuscomm.com:8096/Items?userId=9df468e6f8704f539491d29fbb94e22a&startIndex=0&recursive=true&parentId=a2e45360644bbe9e129101febdc6c19d&fields=MediaSources&includeItemTypes=Audio&imageTypeLimit=1&enableImageTypes=Primary Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushQueue Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::saveQueue Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::updateTrackBlock Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::getTrackBlock Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPlay Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::play index 0 Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::stop Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::play index undefined Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:49 volumio7443 volumio[1151]: info: CoreStateMachine::startPlaybackTimer Mar 29 16:58:49 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:49 volumio7443 volumio[1151]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/song@songId=8368657bfc278b6929b9b88559ca23b5 Mar 29 16:58:49 volumio7443 volumio[1151]: info: [jellyfin-play] Stream URL for 01 My Romance: http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0 Mar 29 16:58:49 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 16:58:49 volumio7443 volumio[1151]: info: sendMpdCommand stop took 4 milliseconds Mar 29 16:58:49 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 16:58:49 volumio7443 volumio[1151]: info: Mar 29 16:58:49 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:58:49 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:58:49 volumio7443 volumio[1151]: info: sendMpdCommand clear took 3 milliseconds Mar 29 16:58:49 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand load "http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf" Mar 29 16:58:49 volumio7443 volumio[1151]: error: updateQueue error: null Mar 29 16:58:49 volumio7443 volumio[1151]: info: ------------------------------ 7ms Mar 29 16:58:49 volumio7443 volumio[1151]: info: Mar 29 16:58:49 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:58:49 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:58:52 volumio7443 kernel: usb 1-1: new low-speed USB device number 5 using xhci_hcd Mar 29 16:58:52 volumio7443 kernel: usb 1-1: device descriptor read/64, error -71 Mar 29 16:58:53 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:53 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:53 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:53 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:53 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:53 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:53 volumio7443 volumio[1151]: verbose: New Socket.io Connection to 192.168.2.139:3000 from 192.168.2.213 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Mar 29 16:58:53 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 16:58:53 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 16:58:54 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 16:58:54 volumio7443 volumio[1151]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 16:58:54 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 16:58:54 volumio7443 volumio[1151]: info: Received Get System Version Mar 29 16:58:54 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 16:58:54 volumio7443 volumio[1151]: info: Received Get System Info Mar 29 16:58:54 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:54 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:54 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:54 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:54 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:54 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:55 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 16:58:55 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 16:58:55 volumio7443 volumio[1151]: info: Discovery: Getting this device information Mar 29 16:58:55 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:55 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:55 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 16:58:55 volumio7443 volumio[1151]: verbose: New Socket.io Connection to 192.168.2.139:3000 from 192.168.2.213 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Mar 29 16:58:55 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 16:58:55 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 16:58:57 volumio7443 volumio5-onboarding[2404]: time=2026-03-29T16:58:57.394+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.213:54434 Mar 29 16:58:57 volumio7443 volumio[1151]: error: updateQueue error: null Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand addid "http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf" Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 7815ms Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:58:57 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand addid "http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf" took 3 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: MPD COMMAND [object Object] Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: MPD COMMAND [object Object] Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: MPD COMMAND [object Object] Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:58:57 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:58:57 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 12ms Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 7ms Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand play Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:58:57 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 7ms Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand play took 3 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 16ms Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces state update: player Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces state update: player Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces state update: player Mar 29 16:58:57 volumio7443 volumio[1151]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand status took 16 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:58:57 volumio7443 volumio[1151]: info: Mar 29 16:58:57 volumio7443 volumio[1151]: ---------------------------- MPD announces state update: player Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:58:57 volumio7443 volumio[1151]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand status took 22 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand status took 22 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand status took 13 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand clearerror took 10 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand status took 22 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand status took 19 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand clearerror took 16 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand clearerror took 16 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand clearerror took 16 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::servicePushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":436,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 My Romance","artist":"CLAUDE WILLIAMSON TRIO","album":"Song For My Father-SACD-VENUS","uri":"http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf","trackType":"dsf"} Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: CURRENT POSITION 0 Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState stateService pause Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState currentStatus stop Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output update for this device Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::servicePushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":436,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 My Romance","artist":"CLAUDE WILLIAMSON TRIO","album":"Song For My Father-SACD-VENUS","uri":"http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf","trackType":"dsf"} Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: CURRENT POSITION 0 Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState stateService pause Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState currentStatus stop Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output update for this device Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:57 volumio7443 volumio5-onboarding[2404]: time=2026-03-29T16:58:57.561+09:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 29 16:58:57 volumio7443 volumio5-onboarding[2404]: time=2026-03-29T16:58:57.561+09:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 68ms Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 66ms Mar 29 16:58:57 volumio7443 volumio[1151]: info: Pushing Favourites {"favourite":false} Mar 29 16:58:57 volumio7443 volumio[1151]: info: Pushing Favourites {"favourite":false} Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::servicePushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":436,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 My Romance","artist":"CLAUDE WILLIAMSON TRIO","album":"Song For My Father-SACD-VENUS","uri":"http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf","trackType":"dsf"} Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: CURRENT POSITION 0 Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState stateService pause Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState currentStatus stop Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output update for this device Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:57 volumio7443 volumio[1151]: info: ControllerMpd::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::servicePushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":436,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 My Romance","artist":"CLAUDE WILLIAMSON TRIO","album":"Song For My Father-SACD-VENUS","uri":"http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf","trackType":"dsf"} Mar 29 16:58:57 volumio7443 volumio[1151]: verbose: CURRENT POSITION 0 Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState stateService pause Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::syncState currentStatus stop Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreStateMachine::pushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushState Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output update for this device Mar 29 16:58:57 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output Mar 29 16:58:57 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:58:57 volumio7443 volumio5-onboarding[2404]: time=2026-03-29T16:58:57.594+09:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 87ms Mar 29 16:58:57 volumio7443 volumio5-onboarding[2404]: time=2026-03-29T16:58:57.596+09:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 29 16:58:57 volumio7443 volumio[1151]: info: ------------------------------ 81ms Mar 29 16:58:57 volumio7443 volumio[1151]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.nARkqtKuRvW59mUHmbxL0cUDLmO2.1b47e65ecd8f19fda587b62437ff42ad.state.status' Mar 29 16:58:57 volumio7443 volumio[1151]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.nARkqtKuRvW59mUHmbxL0cUDLmO2.1b47e65ecd8f19fda587b62437ff42ad.state.status' Mar 29 16:58:57 volumio7443 volumio[1151]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.nARkqtKuRvW59mUHmbxL0cUDLmO2.1b47e65ecd8f19fda587b62437ff42ad.state.status' Mar 29 16:58:57 volumio7443 volumio[1151]: info: [jellyfin-play]: Reported 'pause' for song: 01 My Romance (at 0 ms) Mar 29 16:58:57 volumio7443 volumio[1151]: info: [jellyfin-play]: Mark song 01 My Romance as played by JELL-7443. Mar 29 16:59:00 volumio7443 volumio[1151]: info: Preload queue cleared Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::ClearQueue Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::stop Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::clearPlayQueue Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::saveQueue Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushQueue Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::addQueueItems Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::addQueueItems Mar 29 16:59:00 volumio7443 volumio[1151]: info: Preload queue cleared Mar 29 16:59:00 volumio7443 volumio[1151]: info: Adding Item to queue: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d Mar 29 16:59:00 volumio7443 volumio[1151]: info: Using cached record of: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/playlists/songs@playlistId=a2e45360644bbe9e129101febdc6c19d Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushQueue Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::saveQueue Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::updateTrackBlock Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::getTrackBlock Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPlay Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::play index 0 Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::stop Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::play index undefined Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::startPlaybackTimer Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:59:00 volumio7443 volumio[1151]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/JELL-7443@b69f90bbb9ea49ba8b6f85e2cca331b0/song@songId=8368657bfc278b6929b9b88559ca23b5 Mar 29 16:59:00 volumio7443 volumio[1151]: info: [jellyfin-play] Stream URL for 01 My Romance: http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0 Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 16:59:00 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:59:00 volumio7443 volumio[1151]: info: Mar 29 16:59:00 volumio7443 volumio[1151]: ---------------------------- MPD announces state update: player Mar 29 16:59:00 volumio7443 volumio[1151]: info: sendMpdCommand stop took 6 milliseconds Mar 29 16:59:00 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 16:59:00 volumio7443 volumio[1151]: info: Mar 29 16:59:00 volumio7443 volumio[1151]: ---------------------------- MPD announces state update: player Mar 29 16:59:00 volumio7443 volumio[1151]: info: ControllerMpd::getState Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand status Mar 29 16:59:00 volumio7443 volumio[1151]: info: Mar 29 16:59:00 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:59:00 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:59:00 volumio7443 volumio[1151]: info: sendMpdCommand status took 10 milliseconds Mar 29 16:59:00 volumio7443 volumio[1151]: info: sendMpdCommand status took 7 milliseconds Mar 29 16:59:00 volumio7443 volumio[1151]: info: sendMpdCommand clear took 8 milliseconds Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::sendMpdCommand load "http://p456ad.asuscomm.com:8096/Audio/8368657bfc278b6929b9b88559ca23b5/stream.dsf?static=true&mediaSourceId=8368657bfc278b6929b9b88559ca23b5&tag=9412ab94422bde282f613bc4a14831b0&t.dsf" Mar 29 16:59:00 volumio7443 volumio[1151]: info: Mar 29 16:59:00 volumio7443 volumio[1151]: ---------------------------- MPD announces system playlist update Mar 29 16:59:00 volumio7443 volumio[1151]: info: Ignoring MPD Status Update Mar 29 16:59:00 volumio7443 volumio[1151]: error: updateQueue error: null Mar 29 16:59:00 volumio7443 volumio[1151]: info: sendMpdCommand status took 13 milliseconds Mar 29 16:59:00 volumio7443 volumio[1151]: info: ------------------------------ 13ms Mar 29 16:59:00 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 29 16:59:00 volumio7443 volumio[1151]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::parseState Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:59:00 volumio7443 volumio[1151]: verbose: ControllerMpd::parseTrackInfo Mar 29 16:59:00 volumio7443 volumio[1151]: info: ControllerMpd::pushState Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::servicePushState Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreStateMachine::pushState Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioPushState Mar 29 16:59:00 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output update for this device Mar 29 16:59:00 volumio7443 volumio[1151]: info: MRS: Pushing multiroomSync output Mar 29 16:59:00 volumio7443 volumio[1151]: info: CoreCommandRouter::volumioGetState Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:59:00 volumio7443 volumio[1151]: info: CorePlayQueue::getTrack 0 Mar 29 16:59:00 volumio7443 volumio[1151]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 29 16:59:00 volumio7443 volumio[1151]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 16:59:00 volumio7443 volumio[1151]: info: ------------------------------ 40ms Mar 29 16:59:00 volumio7443 volumio[1151]: info: ------------------------------ 45ms Mar 29 16:59:00 volumio7443 volumio[1151]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 16:59:00 volumio7443 volumio[1151]: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 16:59:00 volumio7443 volumio[1151]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Mar 29 16:59:00 volumio7443 volumio[1151]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 29 16:59:00 volumio7443 volumio[1151]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 29 16:59:00 volumio7443 volumio[1151]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 16:59:00 volumio7443 sudo[102065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 16:58' Mar 29 16:59:00 volumio7443 sudo[102065]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"