Feb 16 16:47:24 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:24 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:27 raspi2 sudo[10657]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 16:47:27 raspi2 sudo[10657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:47:27 raspi2 sudo[10659]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 16:47:27 raspi2 sudo[10657]: pam_unix(sudo:session): session closed for user root Feb 16 16:47:27 raspi2 sudo[10659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:47:27 raspi2 sudo[10659]: pam_unix(sudo:session): session closed for user root Feb 16 16:47:28 raspi2 volumio[1338]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:28 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 16:47:28 raspi2 volumio[1338]: info: Received Get System Info Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 16:47:28 raspi2 volumio[1338]: info: Discovery: Getting this device information Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:28 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:28 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:28 raspi2 volumio[1338]: info: Listing playlists Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 16:47:28 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 16 16:47:29 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetQueue Feb 16 16:47:29 raspi2 volumio[1338]: info: CoreStateMachine::getQueue Feb 16 16:47:29 raspi2 volumio[1338]: info: CorePlayQueue::getQueue Feb 16 16:47:31 raspi2 volumio[1338]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 16:47:31 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 16:47:33 raspi2 volumio[1338]: info: Received Get System Info Feb 16 16:47:33 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 16:47:33 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 16:47:33 raspi2 volumio[1338]: info: Discovery: Getting this device information Feb 16 16:47:33 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:33 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:33 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 16:47:33 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:33 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:33 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 16 16:47:33 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 16:47:34 raspi2 volumio[1338]: info: Received Get System Info Feb 16 16:47:34 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 16:47:34 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 16:47:34 raspi2 volumio[1338]: info: Discovery: Getting this device information Feb 16 16:47:34 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:34 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:34 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 16:47:36 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:36 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:38 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 16 16:47:41 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Feb 16 16:47:41 raspi2 volumio[1338]: info: [jellyfin-browse] browseUri: jellyfin Feb 16 16:47:41 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:43 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Feb 16 16:47:43 raspi2 volumio[1338]: info: [jellyfin-browse] browseUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124 Feb 16 16:47:43 raspi2 volumio[1338]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"d30336057cf84af7885d63b3b063761a","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Feb 16 16:47:43 raspi2 volumio[1338]: verbose: [jellyfin] getItemsFromAPI(): http://100.93.223.101:8096/UserViews?userId=d30336057cf84af7885d63b3b063761a Feb 16 16:47:43 raspi2 volumio[1338]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"d30336057cf84af7885d63b3b063761a","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"4a5c7dd78f12a0180afbf37067b6211a","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Feb 16 16:47:43 raspi2 volumio[1338]: verbose: [jellyfin] getItemsFromAPI(): http://100.93.223.101:8096/Items?userId=d30336057cf84af7885d63b3b063761a&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=4a5c7dd78f12a0180afbf37067b6211a&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Feb 16 16:47:43 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:43 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:46 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 16:47:47 raspi2 volumio[1338]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 16 16:47:47 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 16 16:47:47 raspi2 volumio[1338]: info: Received Get System Version Feb 16 16:47:47 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 16:47:47 raspi2 volumio[1338]: info: Received Get System Info Feb 16 16:47:47 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 16:47:47 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 16:47:47 raspi2 volumio[1338]: info: Discovery: Getting this device information Feb 16 16:47:47 raspi2 volumio[1338]: info: CoreCommandRouter::volumioGetState Feb 16 16:47:47 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:47 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 16:47:49 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Feb 16 16:47:49 raspi2 volumio[1338]: info: [jellyfin-browse] browseUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96 Feb 16 16:47:49 raspi2 volumio[1338]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"d30336057cf84af7885d63b3b063761a","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"e520db1cbd97b6ae8caa3427bf160e96","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Feb 16 16:47:49 raspi2 volumio[1338]: verbose: [jellyfin] getItemsFromAPI(): http://100.93.223.101:8096/Items?userId=d30336057cf84af7885d63b3b063761a&startIndex=0&recursive=true&sortOrder=Ascending&parentId=e520db1cbd97b6ae8caa3427bf160e96&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Feb 16 16:47:50 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:47:50 raspi2 volumio[1338]: info: Preloading song: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:47:50 raspi2 volumio[1338]: info: No valid Plugin REST Endpoint Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:47:50 raspi2 volumio[1338]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 in service jellyfin Feb 16 16:47:50 raspi2 volumio[1338]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:47:51 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::ClearQueue Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::stop Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::stPlaybackTimer Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::updateTrackBlock Feb 16 16:47:51 raspi2 volumio[1338]: info: CorePlayQueue::getTrackBlock Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::pushState Feb 16 16:47:51 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushState Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::serviceStop Feb 16 16:47:51 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreCommandRouter::serviceStop Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 16 16:47:51 raspi2 volumio[1338]: info: ControllerMpd::stop Feb 16 16:47:51 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 16:47:51 raspi2 volumio[1338]: info: CorePlayQueue::clearPlayQueue Feb 16 16:47:51 raspi2 volumio[1338]: info: CorePlayQueue::saveQueue Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreStateMachine::addQueueItems Feb 16 16:47:51 raspi2 volumio[1338]: info: CorePlayQueue::addQueueItems Feb 16 16:47:51 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:51 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:51 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:51 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::saveQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::updateTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPlay Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::play index 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::addQueueItems Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::addQueueItems Feb 16 16:47:52 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::stop Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::saveQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::play index undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::updateTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:52 raspi2 volumio[1338]: info: ControllerMpd::getState Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Feb 16 16:47:52 raspi2 volumio[1338]: info: Feb 16 16:47:52 raspi2 volumio[1338]: ---------------------------- MPD announces state update: player Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand stop took 103 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: info: ControllerMpd::getState Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand status took 5 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand status took 4 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::parseState Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::parseState Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Feb 16 16:47:52 raspi2 volumio[1338]: info: ControllerMpd::pushState Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::servicePushState Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::pushState Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushState Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 16 16:47:52 raspi2 volumio[1338]: info: ------------------------------ 39ms Feb 16 16:47:52 raspi2 volumio[1338]: info: Pushing Favourites {"favourite":false} Feb 16 16:47:52 raspi2 volumio[1338]: info: [jellyfin-play]: Reported 'stop' for song: Popurrí Musita (at 53305 ms) Feb 16 16:47:52 raspi2 volumio[1338]: info: [jellyfin-play] Stream URL for Every Breath You Take: http://100.93.223.101:8096/Audio/91a374c2dc300a99d23c6e04474bf6b9/stream.flac?static=true&mediaSourceId=91a374c2dc300a99d23c6e04474bf6b9&tag=8b47fefdfdd0cb21cd7e65ed53237b22 Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand stop took 6 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 16:47:52 raspi2 volumio[1338]: info: Feb 16 16:47:52 raspi2 volumio[1338]: ---------------------------- MPD announces system playlist update Feb 16 16:47:52 raspi2 volumio[1338]: info: Ignoring MPD Status Update Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand clear took 3 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand load "http://100.93.223.101:8096/Audio/91a374c2dc300a99d23c6e04474bf6b9/stream.flac?static=true&mediaSourceId=91a374c2dc300a99d23c6e04474bf6b9&tag=8b47fefdfdd0cb21cd7e65ed53237b22&t.flac" Feb 16 16:47:52 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::ClearQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::stop Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::clearPlayQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::saveQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::addQueueItems Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::addQueueItems Feb 16 16:47:52 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::saveQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::updateTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPlay Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::play index 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::addQueueItems Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::addQueueItems Feb 16 16:47:52 raspi2 volumio[1338]: info: Preload queue cleared Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:47:52 raspi2 volumio[1338]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:47:52 raspi2 volumio[1338]: info: Using cached record of: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::stop Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::saveQueue Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::play index undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::updateTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrackBlock Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Feb 16 16:47:52 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 0 Feb 16 16:47:52 raspi2 volumio[1338]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:47:52 raspi2 volumio[1338]: error: updateQueue error: null Feb 16 16:47:52 raspi2 volumio[1338]: info: ------------------------------ 65ms Feb 16 16:47:52 raspi2 volumio[1338]: info: [jellyfin-play] Stream URL for Every Breath You Take: http://100.93.223.101:8096/Audio/91a374c2dc300a99d23c6e04474bf6b9/stream.flac?static=true&mediaSourceId=91a374c2dc300a99d23c6e04474bf6b9&tag=8b47fefdfdd0cb21cd7e65ed53237b22 Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand addid "http://100.93.223.101:8096/Audio/91a374c2dc300a99d23c6e04474bf6b9/stream.flac?static=true&mediaSourceId=91a374c2dc300a99d23c6e04474bf6b9&tag=8b47fefdfdd0cb21cd7e65ed53237b22&t.flac" Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand stop took 85 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 16:47:52 raspi2 volumio[1338]: info: Feb 16 16:47:52 raspi2 volumio[1338]: ---------------------------- MPD announces system playlist update Feb 16 16:47:52 raspi2 volumio[1338]: info: Ignoring MPD Status Update Feb 16 16:47:52 raspi2 volumio[1338]: info: Feb 16 16:47:52 raspi2 volumio[1338]: ---------------------------- MPD announces system playlist update Feb 16 16:47:52 raspi2 volumio[1338]: info: Ignoring MPD Status Update Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand addid "http://100.93.223.101:8096/Audio/91a374c2dc300a99d23c6e04474bf6b9/stream.flac?static=true&mediaSourceId=91a374c2dc300a99d23c6e04474bf6b9&tag=8b47fefdfdd0cb21cd7e65ed53237b22&t.flac" took 20 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: info: sendMpdCommand clear took 19 milliseconds Feb 16 16:47:52 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand load "http://100.93.223.101:8096/Audio/91a374c2dc300a99d23c6e04474bf6b9/stream.flac?static=true&mediaSourceId=91a374c2dc300a99d23c6e04474bf6b9&tag=8b47fefdfdd0cb21cd7e65ed53237b22&t.flac" Feb 16 16:47:52 raspi2 volumio[1338]: verbose: MPD COMMAND [object Object] Feb 16 16:47:52 raspi2 volumio[1338]: verbose: MPD COMMAND [object Object] Feb 16 16:47:52 raspi2 volumio[1338]: verbose: MPD COMMAND [object Object] Feb 16 16:47:52 raspi2 volumio[1338]: error: updateQueue error: null Feb 16 16:47:52 raspi2 volumio[1338]: error: updateQueue error: null Feb 16 16:47:52 raspi2 volumio[1338]: info: ------------------------------ 23ms Feb 16 16:47:52 raspi2 volumio[1338]: info: ------------------------------ 21ms Feb 16 16:47:52 raspi2 volumio[1338]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 16:47:52 raspi2 volumio[1338]: Error: [50@0] {addtagid} No such song Feb 16 16:47:52 raspi2 volumio[1338]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17) Feb 16 16:47:52 raspi2 volumio[1338]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 16 16:47:52 raspi2 volumio[1338]: at Socket.emit (node:events:514:28) Feb 16 16:47:52 raspi2 volumio[1338]: at addChunk (node:internal/streams/readable:343:12) Feb 16 16:47:52 raspi2 volumio[1338]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 16 16:47:52 raspi2 volumio[1338]: at Readable.push (node:internal/streams/readable:253:10) Feb 16 16:47:52 raspi2 volumio[1338]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 16 16:47:52 raspi2 volumio[1338]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 16:47:52 raspi2 ntpd[821]: PROTO: 132.248.3.29 unlink local addr 192.168.3.187 -> Feb 16 16:47:54 raspi2 sudo[10729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 16:46' Feb 16 16:47:54 raspi2 sudo[10729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:47:54 raspi2 sudo[10729]: pam_unix(sudo:session): session closed for user root Feb 16 16:47:55 raspi2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 16 16:47:55 raspi2 systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 16 16:47:55 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:47:55.569-06:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 16 16:47:55 raspi2 volumio-remote-updater[657]: [2026-02-16 16:47:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 16:47:55 raspi2 systemd[1]: volumio.service: Consumed 24min 6.534s CPU time. Feb 16 16:47:55 raspi2 volumio-remote-updater[657]: [2026-02-16 16:47:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 16:47:55 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:47:55.736-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 16 16:47:55 raspi2 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 16:47:55 raspi2 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 16:47:55 raspi2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 16 16:47:55 raspi2 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 16 16:47:55 raspi2 systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 16 16:47:55 raspi2 systemd[1]: volumio.service: Consumed 24min 6.534s CPU time. Feb 16 16:47:55 raspi2 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 16 16:47:55 raspi2 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 16 16:47:56 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:47:56.739-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 16 16:47:57 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:47:57.743-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 16 16:47:58 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:47:58.747-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 16 16:47:59 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:47:59.751-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 16 16:48:00 raspi2 volumio-remote-updater[657]: [2026-02-16 16:48:00] [connect] Successful connection Feb 16 16:48:01 raspi2 volumio[10769]: info: ------------------------------------------- Feb 16 16:48:01 raspi2 volumio[10769]: info: ----- Volumio3 ---- Feb 16 16:48:01 raspi2 volumio[10769]: info: ------------------------------------------- Feb 16 16:48:01 raspi2 volumio[10769]: info: ----- System startup ---- Feb 16 16:48:01 raspi2 volumio[10769]: info: ------------------------------------------- Feb 16 16:48:03 raspi2 volumio[10769]: info: MYVOLUMIO Environment detected Feb 16 16:48:03 raspi2 volumio[10769]: info: Plugin folders cleanup Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning into folder /volumio/app/plugins/ Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category audio_interface Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category miscellanea Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category music_service Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category plugins.json Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category system_controller Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category user_interface Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning into folder /data/plugins/ Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category audio_interface Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category music_service Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category system_controller Feb 16 16:48:03 raspi2 volumio[10769]: info: Scanning category system_hardware Feb 16 16:48:03 raspi2 volumio[10769]: info: Plugin folders cleanup completed Feb 16 16:48:03 raspi2 volumio[10769]: info: ------------------------------------------- Feb 16 16:48:03 raspi2 volumio[10769]: info: ----- Core plugins startup ---- Feb 16 16:48:03 raspi2 volumio[10769]: info: ------------------------------------------- Feb 16 16:48:03 raspi2 volumio[10769]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 16:48:03 raspi2 volumio[10769]: info: Adding plugin upnp to MyMusic Plugins Feb 16 16:48:03 raspi2 volumio[10769]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 16:48:03 raspi2 volumio[10769]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 16:48:03 raspi2 volumio[10769]: info: Loading plugins from folder /data/plugins/ Feb 16 16:48:03 raspi2 volumio[10769]: info: Loading plugin "system"... Feb 16 16:48:03 raspi2 volumio[10769]: info: Loading plugin "appearance"... Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "network"... Feb 16 16:48:07 raspi2 volumio[10769]: info: Refreshing Cached IP Addresses Feb 16 16:48:07 raspi2 sudo[10799]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 16:48:07 raspi2 sudo[10799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:07 raspi2 sudo[10801]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 16:48:07 raspi2 sudo[10801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:07 raspi2 sudo[10799]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "services"... Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "volumio5onboarding"... Feb 16 16:48:07 raspi2 sudo[10801]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "alsa_controller"... Feb 16 16:48:07 raspi2 sudo[10811]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 16 16:48:07 raspi2 sudo[10811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:07 raspi2 sudo[10811]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:07 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "wizard"... Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "networkfs"... Feb 16 16:48:07 raspi2 volumio[10769]: info: Starting Udev Watcher for removable devices Feb 16 16:48:07 raspi2 volumio[10769]: info: Ignoring mount for partition: boot Feb 16 16:48:07 raspi2 volumio[10769]: info: Ignoring mount for partition: volumio Feb 16 16:48:07 raspi2 volumio[10769]: info: Ignoring mount for partition: volumio_data Feb 16 16:48:07 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "volumio_command_line_client"... Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "upnp"... Feb 16 16:48:07 raspi2 volumio[10769]: info: [1771282087829] Starting Upmpd Daemon Feb 16 16:48:07 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "my_music"... Feb 16 16:48:07 raspi2 volumio[10769]: info: Loading plugin "mpd"... Feb 16 16:48:08 raspi2 volumio[10769]: info: Loading plugin "upnp_browser"... Feb 16 16:48:10 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:48:10.755-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:50902->127.0.0.1:3000: i/o timeout" Feb 16 16:48:11 raspi2 volumio[10769]: info: Starting UPNP Browser Feb 16 16:48:11 raspi2 volumio[10769]: info: Loading plugin "alarm-clock"... Feb 16 16:48:12 raspi2 volumio[10769]: info: Loading plugin "airplay_emulation"... Feb 16 16:48:12 raspi2 volumio[10769]: info: Starting Shairport Sync Feb 16 16:48:12 raspi2 volumio[10769]: info: Loading plugin "last_100"... Feb 16 16:48:12 raspi2 volumio[10769]: info: Loading plugin "webradio"... Feb 16 16:48:12 raspi2 volumio[10769]: info: Loading plugin "i2s_dacs"... Feb 16 16:48:12 raspi2 volumio[10769]: info: Loading plugin "volumiodiscovery"... Feb 16 16:48:12 raspi2 volumio[10769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 16:48:12 raspi2 volumio[10769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 16:48:12 raspi2 volumio[10769]: *** WARNING *** For more information see Feb 16 16:48:12 raspi2 volumio[10769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 16:48:12 raspi2 volumio[10769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 16:48:12 raspi2 node[10769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 16:48:12 raspi2 volumio[10769]: *** WARNING *** For more information see Feb 16 16:48:12 raspi2 node[10769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 16:48:12 raspi2 node[10769]: *** WARNING *** For more information see Feb 16 16:48:12 raspi2 node[10769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 16:48:12 raspi2 node[10769]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 16:48:12 raspi2 node[10769]: *** WARNING *** For more information see Feb 16 16:48:12 raspi2 volumio[10769]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 16:48:12 raspi2 volumio[10769]: info: Discovery: Started advertising with name: Raspi2 Feb 16 16:48:12 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 16:48:12 raspi2 volumio[10769]: info: Loading plugin "jellyfin"... Feb 16 16:48:13 raspi2 volumio-remote-updater[657]: [2026-02-16 16:48:13] [connect] Successful connection Feb 16 16:48:15 raspi2 volumio[10769]: info: Loading plugin "outputs"... Feb 16 16:48:15 raspi2 volumio[10769]: info: Loading plugin "albumart"... Feb 16 16:48:15 raspi2 volumio[10769]: info: Plugin example_plugin is not enabled Feb 16 16:48:15 raspi2 volumio[10769]: info: Loading plugin "inputs"... Feb 16 16:48:15 raspi2 volumio[10769]: info: Loading plugin "updater_comm"... Feb 16 16:48:16 raspi2 volumio[10769]: info: Plugin mpdemulation is not enabled Feb 16 16:48:16 raspi2 volumio[10769]: info: Loading plugin "rest_api"... Feb 16 16:48:16 raspi2 volumio[10769]: info: Loading plugin "websocket"... Feb 16 16:48:16 raspi2 volumio[10769]: info: Starting Socket.io Server version 1.7.4 Feb 16 16:48:16 raspi2 volumio[10769]: info: Loading plugin "fusiondsp"... Feb 16 16:48:16 raspi2 volumio[10838]: Forking 3 albumart workers Feb 16 16:48:17 raspi2 volumio[10769]: info: Applying required configuration parameters for plugin fusiondsp Feb 16 16:48:18 raspi2 volumio[10769]: info: Loading plugin "backup_restore"... Feb 16 16:48:18 raspi2 volumio[10769]: info: Applying required configuration parameters for plugin backup_restore Feb 16 16:48:18 raspi2 volumio[10769]: info: Loading plugin "Bluetoothremote"... Feb 16 16:48:19 raspi2 volumio[10769]: info: Applying required configuration parameters for plugin Bluetoothremote Feb 16 16:48:19 raspi2 volumio[10769]: info: Loading plugin "remotepi"... Feb 16 16:48:19 raspi2 volumio[10849]: Starting albumart workers Feb 16 16:48:20 raspi2 volumio[10848]: Starting albumart workers Feb 16 16:48:20 raspi2 volumio[10850]: Starting albumart workers Feb 16 16:48:20 raspi2 volumio[10769]: info: Applying required configuration parameters for plugin remotepi Feb 16 16:48:20 raspi2 volumio[10769]: info: Loading i18n strings for locale es Feb 16 16:48:20 raspi2 volumio[10769]: error: remotepi: Fetching language file: Error: i18n file complementing the system language not found. Feb 16 16:48:20 raspi2 volumio[10769]: Updating browse sources language Feb 16 16:48:20 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::initPlayerControls Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 16:48:21 raspi2 volumio[10769]: Express server listening on port 3000 Feb 16 16:48:21 raspi2 volumio[10769]: [Metrics] WebUI: 21s 580.90ms Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreStateMachine::resetVolumioState Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreStateMachine::getcurrentVolume Feb 16 16:48:21 raspi2 volumio[10769]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 16:48:21 raspi2 sudo[10899]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 16:48:21 raspi2 sudo[10899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:21 raspi2 sudo[10901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 16:48:21 raspi2 volumio5-onboarding[2063]: time=2026-02-16T16:48:21.757-06:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:38384->127.0.0.1:3000: i/o timeout" Feb 16 16:48:21 raspi2 sudo[10901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:21 raspi2 sudo[10899]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:21 raspi2 sudo[10901]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:21 raspi2 volumio[10769]: info: Volumio Network Manager: Network status updated: 1 Feb 16 16:48:21 raspi2 volumio[10769]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 16 16:48:21 raspi2 volumio[10769]: wlan0 Interface doesn't support scanning. Feb 16 16:48:21 raspi2 volumio[10769]: info: Cannot use regular scanning, forcing with ap-force Feb 16 16:48:22 raspi2 sudo[10922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 16 16:48:22 raspi2 sudo[10922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:22 raspi2 sudo[10922]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:22 raspi2 volumio[10769]: command failed: No such device (-19) Feb 16 16:48:22 raspi2 volumio[10769]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 16 16:48:22 raspi2 volumio[10769]: command failed: No such device (-19) Feb 16 16:48:22 raspi2 volumio[10769]: info: VolumeController:: Volume=57 Mute =false Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreStateMachine::pushState Feb 16 16:48:22 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::volumioPushState Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreStateMachine::updateTrackBlock Feb 16 16:48:22 raspi2 volumio[10769]: info: CorePlayQueue::getTrackBlock Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 16:48:22 raspi2 volumio[10769]: info: Reloading queue from file Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:22 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 1 Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreStateMachine::setRepeat null single undefined Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreStateMachine::pushState Feb 16 16:48:22 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::volumioPushState Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreStateMachine::setRandom null Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreStateMachine::pushState Feb 16 16:48:22 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::volumioPushState Feb 16 16:48:22 raspi2 volumio[10769]: info: Setting Device type: Raspberry PI Feb 16 16:48:22 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 2 Feb 16 16:48:22 raspi2 volumio[10769]: info: Completed loading Core Plugins Feb 16 16:48:22 raspi2 volumio[10769]: info: Preparing to generate the ALSA configuration file Feb 16 16:48:22 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 3 Feb 16 16:48:22 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 4 Feb 16 16:48:22 raspi2 volumio[10769]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 16 16:48:22 raspi2 volumio[10769]: info: Reading ALSA contributions from plugins. Feb 16 16:48:22 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 16 16:48:22 raspi2 volumio[10769]: info: VolumeController:: Volume=57 Mute =false Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreStateMachine::pushState Feb 16 16:48:22 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:22 raspi2 volumio[10769]: info: CoreCommandRouter::volumioPushState Feb 16 16:48:22 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 16 16:48:22 raspi2 sudo[10943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 16:48:22 raspi2 sudo[10943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:23 raspi2 volumio[10769]: info: Discovery: adding 681a86ee-8311-4198-a64b-b8c501747964 Feb 16 16:48:23 raspi2 volumio[10769]: info: Discovery: Found device Raspi2 Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:23 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:23 raspi2 sudo[10943]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:23 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 16 16:48:23 raspi2 volumio[10769]: info: Upmpdcli Daemon Started Feb 16 16:48:23 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 16 16:48:23 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Feb 16 16:48:23 raspi2 volumio[10769]: info: Asound.conf file unchanged, so no further update is needed Feb 16 16:48:23 raspi2 volumio[10769]: info: Output device has changed, restarting MPD Feb 16 16:48:23 raspi2 volumio[10769]: info: Output device has changed, restarting Shairport Sync Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:23 raspi2 sudo[10949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 16:48:23 raspi2 sudo[10949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:23 raspi2 sudo[10947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 16:48:23 raspi2 sudo[10947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:23 raspi2 volumio[10769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 16:48:23 raspi2 volumio[10769]: info: ___________ START PLUGINS ___________ Feb 16 16:48:23 raspi2 sudo[10947]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:23 raspi2 volumio[10769]: info: ControllerMpd::onStart: Initializing MPD Feb 16 16:48:23 raspi2 volumio[10769]: info: Creating MPD Configuration file Feb 16 16:48:23 raspi2 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 16:48:23 raspi2 volumio[10769]: info: [1771282103561] CoreMusicLibrary::Adding element Media Servers Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 16:48:23 raspi2 sudo[10957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 16 16:48:23 raspi2 sudo[10957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:23 raspi2 volumio[10769]: info: UPNP Browser: Client initialized successfully Feb 16 16:48:23 raspi2 sudo[10959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 16:48:23 raspi2 sudo[10959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:23 raspi2 sudo[10961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 16:48:23 raspi2 sudo[10961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:23 raspi2 upmpdcli[1514]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Feb 16 16:48:23 raspi2 sudo[10959]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:23 raspi2 volumio[10769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 16:48:23 raspi2 sudo[10957]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:23 raspi2 volumio[10769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 16:48:23 raspi2 volumio[10769]: info: [1771282103893] CoreMusicLibrary::Adding element Last_100 Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 16:48:23 raspi2 volumio[10769]: info: [1771282103912] CoreMusicLibrary::Adding element Webradio Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 16:48:23 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 16:48:23 raspi2 volumio[10769]: info: Initializing BBC Radios Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 16:48:24 raspi2 volumio[10769]: info: Discovery: Getting this device information Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:24 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 16:48:24 raspi2 volumio[10769]: info: [1771282104226] CoreMusicLibrary::Adding element Jellyfin Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 16:48:24 raspi2 volumio[10769]: Cannot find translation for source Jellyfin Feb 16 16:48:24 raspi2 volumio[10769]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.3.187","id":"681a86ee-8311-4198-a64b-b8c501747964","name":"Raspi2","serviceName":"Volumio","state":{"albumart":"http://100.93.223.101:8096/Items/91a374c2dc300a99d23c6e04474bf6b9/Images/Primary?maxWidth=500&maxHeight=500&quality=90","artist":"The Police","mute":false,"status":"stop","track":"Every Breath You Take","volume":57},"type":"device"} Feb 16 16:48:24 raspi2 volumio[10769]: info: FusionDsp - CamillaDSP binary found. Feb 16 16:48:24 raspi2 volumio[10769]: info: Loading i18n strings for locale es Feb 16 16:48:24 raspi2 volumio[10769]: error: remotepi: Fetching language file: Error: i18n file complementing the system language not found. Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 16 16:48:24 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:24 raspi2 volumio[10769]: info: FusionDsp - mixtype--------------------- Hardware Feb 16 16:48:24 raspi2 volumio[10769]: info: Loading i18n strings for locale es Feb 16 16:48:24 raspi2 volumio[10769]: error: remotepi: Fetching language file: Error: i18n file complementing the system language not found. Feb 16 16:48:24 raspi2 volumio[10769]: info: Loading i18n strings for locale es Feb 16 16:48:24 raspi2 volumio[10769]: error: remotepi: Fetching language file: Error: i18n file complementing the system language not found. Feb 16 16:48:24 raspi2 volumio[10769]: info: Volumio Calling Home Feb 16 16:48:24 raspi2 volumio[10769]: info: Preparing to generate the ALSA configuration file Feb 16 16:48:25 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Feb 16 16:48:25 raspi2 volumio[10769]: info: Discovery: this is already registered, 681a86ee-8311-4198-a64b-b8c501747964 Feb 16 16:48:25 raspi2 volumio[10769]: info: Discovery: Found device Raspi2 Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:25 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:25 raspi2 volumio[10769]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 16 16:48:25 raspi2 volumio[10769]: info: Reading ALSA contributions from plugins. Feb 16 16:48:25 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 9 Feb 16 16:48:25 raspi2 tailscaled[652]: dns: resolver: forward: recv: response code indicating server failure: 2 Feb 16 16:48:25 raspi2 volumio[10769]: info: MPD Permissions set Feb 16 16:48:25 raspi2 volumio[10769]: info: MPD Permissions set Feb 16 16:48:25 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 10 Feb 16 16:48:25 raspi2 volumio[10769]: info: Volumio called home Feb 16 16:48:25 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:25 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 16 16:48:26 raspi2 sudo[11003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod o+rx /sys/kernel/debug Feb 16 16:48:26 raspi2 sudo[11003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:26 raspi2 sudo[11003]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:26 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Feb 16 16:48:26 raspi2 volumio[10769]: info: [jellyfin-poller] Polled http://100.93.223.101:8096: online Feb 16 16:48:26 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 12 Feb 16 16:48:26 raspi2 volumio[10769]: info: Starting Shairport Sync Feb 16 16:48:26 raspi2 volumio[10769]: info: Starting Shairport Sync Feb 16 16:48:26 raspi2 volumio[10769]: info: Starting Shairport Sync Feb 16 16:48:26 raspi2 sudo[11008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 16:48:26 raspi2 sudo[11008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:26 raspi2 sudo[11006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 16:48:26 raspi2 sudo[11006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:26 raspi2 sudo[11010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 16:48:26 raspi2 sudo[11010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:26 raspi2 volumio[10769]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Feb 16 16:48:26 raspi2 volumio[10769]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Feb 16 16:48:26 raspi2 volumio[10769]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Feb 16 16:48:26 raspi2 volumio[10769]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 16 16:48:26 raspi2 volumio[10769]: info: camilladsp service started and running in background, instance 1 Feb 16 16:48:26 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:26 raspi2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 16 16:48:26 raspi2 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 16 16:48:26 raspi2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 16 16:48:26 raspi2 systemd[1]: shairport-sync.service: Consumed 48.074s CPU time. Feb 16 16:48:26 raspi2 volumio[10769]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Feb 16 16:48:26 raspi2 volumio[10769]: info: FusionDsp loaded Feb 16 16:48:26 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 16:48:26 raspi2 volumio[10769]: info: FusionDsp - --[object Object] Feb 16 16:48:26 raspi2 volumio[10769]: error: FusionDsp - Socket connection not established Feb 16 16:48:26 raspi2 volumio[10769]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 16 16:48:26 raspi2 volumio[10769]: info: Adding Signal Path Element [object Object] Feb 16 16:48:26 raspi2 volumio[10769]: info: Adding fusiondspeq DSP Signal Path Element Feb 16 16:48:26 raspi2 volumio[10769]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Feb 16 16:48:26 raspi2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 16 16:48:26 raspi2 sudo[11010]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:26 raspi2 sudo[11008]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:26 raspi2 sudo[11006]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:26 raspi2 sudo[11030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 16 16:48:26 raspi2 sudo[11030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:27 raspi2 sudo[11030]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:27 raspi2 volumio[10769]: info: Asound.conf file unchanged, so no further update is needed Feb 16 16:48:27 raspi2 volumio[10769]: info: Output device has changed, restarting MPD Feb 16 16:48:27 raspi2 volumio[10769]: info: Output device has changed, restarting Shairport Sync Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:27 raspi2 sudo[11035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 16:48:27 raspi2 sudo[11035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:27 raspi2 sudo[11035]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:27 raspi2 sudo[11037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 16:48:27 raspi2 sudo[11037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:27 raspi2 volumio[10769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 16:48:27 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Feb 16 16:48:27 raspi2 volumio[10769]: error: FusionDsp - Reload WebSocket error: [object Object] Feb 16 16:48:27 raspi2 volumio[10769]: info: Shairport-Sync Started Feb 16 16:48:27 raspi2 volumio[10769]: Error adding Membership: Error: addMembership EINVAL Feb 16 16:48:27 raspi2 volumio[10769]: info: Shairport-Sync Started Feb 16 16:48:27 raspi2 volumio[10769]: info: Shairport-Sync Started Feb 16 16:48:27 raspi2 volumio[10769]: info: MPD Permissions set Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:27 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:27 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 16:48:27 raspi2 volumio[10769]: info: Received Get System Info Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 16:48:27 raspi2 volumio[10769]: info: Discovery: Getting this device information Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:27 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:27 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:27 raspi2 volumio[10769]: info: Listing playlists Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetQueue Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreStateMachine::getQueue Feb 16 16:48:27 raspi2 volumio[10769]: info: CorePlayQueue::getQueue Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 16:48:27 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 16:48:28 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Feb 16 16:48:28 raspi2 volumio[10769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 13 Feb 16 16:48:28 raspi2 volumio-remote-updater[657]: [2026-02-16 16:48:28] [connect] Successful connection Feb 16 16:48:28 raspi2 volumio[10769]: info: CoreCommandRouter::volumioGetState Feb 16 16:48:28 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:28 raspi2 volumio[10769]: info: Starting Shairport Sync Feb 16 16:48:28 raspi2 volumio-remote-updater[657]: [2026-02-16 16:48:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771282108 101 Feb 16 16:48:28 raspi2 volumio[10769]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 14 Feb 16 16:48:28 raspi2 volumio[10769]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 16:48:28 raspi2 sudo[11079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 16:48:28 raspi2 sudo[11079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 16 16:48:28 raspi2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 16 16:48:28 raspi2 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 16 16:48:28 raspi2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 16 16:48:28 raspi2 systemd[1]: shairport-sync.service: Consumed 1.601s CPU time. Feb 16 16:48:28 raspi2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 16 16:48:28 raspi2 sudo[11079]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:29 raspi2 volumio[10769]: info: Shairport-Sync Started Feb 16 16:48:29 raspi2 upmpdcli[1514]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Feb 16 16:48:29 raspi2 upmpdcli[1514]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed Feb 16 16:48:29 raspi2 volumio[10769]: info: Preload queue cleared Feb 16 16:48:29 raspi2 volumio[10769]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 16 16:48:29 raspi2 volumio[10769]: info: CoreStateMachine::ClearQueue Feb 16 16:48:29 raspi2 volumio[10769]: info: CoreStateMachine::stop Feb 16 16:48:29 raspi2 upmpdcli[1514]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Feb 16 16:48:29 raspi2 volumio[10769]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:48:29 raspi2 volumio[10769]: info: CorePlayQueue::clearPlayQueue Feb 16 16:48:29 raspi2 volumio[10769]: info: CorePlayQueue::saveQueue Feb 16 16:48:29 raspi2 volumio[10769]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:48:29 raspi2 volumio[10769]: info: CoreStateMachine::addQueueItems Feb 16 16:48:29 raspi2 volumio[10769]: info: CorePlayQueue::addQueueItems Feb 16 16:48:29 raspi2 volumio[10769]: info: Preload queue cleared Feb 16 16:48:29 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:48:29 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 in service jellyfin Feb 16 16:48:29 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:48:29 raspi2 volumio[10769]: info: [jellyfin-conn] Using previously assigned device Id for admin@7fa114bd75e8: 41fcc848-d28b-4164-b0e7-54c1497b0e60 Feb 16 16:48:29 raspi2 volumio[10769]: verbose: New Socket.io Connection to 192.168.3.187 from 192.168.3.70 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 15 Feb 16 16:48:29 raspi2 volumio[10769]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 16:48:30 raspi2 systemd[1]: mpd.service: Deactivated successfully. Feb 16 16:48:30 raspi2 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 16 16:48:30 raspi2 systemd[1]: mpd.service: Consumed 3min 33.104s CPU time. Feb 16 16:48:30 raspi2 systemd[1]: mpd.socket: Deactivated successfully. Feb 16 16:48:30 raspi2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 16 16:48:30 raspi2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 16 16:48:30 raspi2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 16 16:48:30 raspi2 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-conn] Login successful: admin@7fa114bd75e8 Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreCommandRouter::volumioPushQueue Feb 16 16:48:30 raspi2 volumio[10769]: info: CorePlayQueue::saveQueue Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::updateTrackBlock Feb 16 16:48:30 raspi2 volumio[10769]: info: CorePlayQueue::getTrackBlock Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreCommandRouter::volumioPlay Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::play index 0 Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::addQueueItems Feb 16 16:48:30 raspi2 volumio[10769]: info: CorePlayQueue::addQueueItems Feb 16 16:48:30 raspi2 volumio[10769]: info: Preload queue cleared Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=e1894644ba141a6c7261de52d56eb528 Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9f40030d83deadb2538a161264dc7abc Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=5087b77479a71c7cc0d9fe15b68cb4f4 Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=9ebfa95d89190f7045e62edfcb16bac2 Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=d3aa97e096384d6227aaef285f83c2d3 Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=2c9e9650b1a3f7928cb174fd6057e1e0 Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=431533c4f0d7b7549cb454197cb7f480 Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=0b2c9d7388412b8994bf2b74614d7dd0 Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=a9625036349e8671cabcfd66135f256f Feb 16 16:48:30 raspi2 volumio[10769]: info: Adding Item to queue: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:48:30 raspi2 volumio[10769]: info: Exploding uri jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 in service jellyfin Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-browse] explodeUri: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/songs@albumId=e520db1cbd97b6ae8caa3427bf160e96/song@songId=f070a9a585e927323d06f706a44f2cb5 Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::stop Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::play index undefined Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 16:48:30 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:30 raspi2 volumio[10769]: info: CoreStateMachine::startPlaybackTimer Feb 16 16:48:30 raspi2 volumio[10769]: info: CorePlayQueue::getTrack 0 Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/admin@6bc0268ab0324cb1b2d4940ee8e33124/song@songId=91a374c2dc300a99d23c6e04474bf6b9 Feb 16 16:48:30 raspi2 sudo[11097]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 16:48:30 raspi2 sudo[11097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 16 16:48:30 raspi2 sudo[11097]: pam_unix(sudo:session): session closed for user root Feb 16 16:48:30 raspi2 volumio[10769]: info: [jellyfin-play] Stream URL for Every Breath You Take: http://100.93.223.101:8096/Audio/91a374c2dc300a99d23c6e04474bf6b9/stream.flac?static=true&mediaSourceId=91a374c2dc300a99d23c6e04474bf6b9&tag=8b47fefdfdd0cb21cd7e65ed53237b22 Feb 16 16:48:30 raspi2 volumio[10769]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 16:48:30 raspi2 volumio[10769]: TypeError: Cannot read properties of undefined (reading 'on') Feb 16 16:48:30 raspi2 volumio[10769]: at PlayController._PlayController_addListeners (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:184:79) Feb 16 16:48:30 raspi2 volumio[10769]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:80:100) Feb 16 16:48:30 raspi2 volumio[10769]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 16 16:48:30 raspi2 volumio[10769]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 16:48:32 raspi2 sudo[11114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-16 16:47' Feb 16 16:48:32 raspi2 sudo[11114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"