Feb 16 16:46:18 raspi2 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 16 16:46:18 raspi2 nmbd[1019]: [2026/02/16 16:46:18.545088, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 16 16:46:18 raspi2 nmbd[1019]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.187 for name WORKGROUP<1d>. Feb 16 16:46:18 raspi2 nmbd[1019]: This response was from IP 192.168.3.70, reporting an IP address of 192.168.3.70. Feb 16 16:46:20 raspi2 systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 16 16:46:20 raspi2 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 16 16:46:20 raspi2 systemd[1]: setdatetime-helper.service: Consumed 1.947s CPU time. Feb 16 16:46:51 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 23 Feb 16 16:46:51 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:51 raspi2 volumio[1338]: info: Prefetching next song Feb 16 16:46:51 raspi2 volumio[1338]: info: [jellyfin-play] Stream URL for Popurrí Musita: http://100.93.223.101:8096/Audio/c3293a00589a4c52b41b4c20ff1156d8/stream.flac?static=true&mediaSourceId=c3293a00589a4c52b41b4c20ff1156d8&tag=34fc0e3963d8f6d2296bcb72446b1409 Feb 16 16:46:51 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand addid "http://100.93.223.101:8096/Audio/c3293a00589a4c52b41b4c20ff1156d8/stream.flac?static=true&mediaSourceId=c3293a00589a4c52b41b4c20ff1156d8&tag=34fc0e3963d8f6d2296bcb72446b1409&t.flac" Feb 16 16:46:51 raspi2 volumio[1338]: info: sendMpdCommand addid "http://100.93.223.101:8096/Audio/c3293a00589a4c52b41b4c20ff1156d8/stream.flac?static=true&mediaSourceId=c3293a00589a4c52b41b4c20ff1156d8&tag=34fc0e3963d8f6d2296bcb72446b1409&t.flac" took 2 milliseconds Feb 16 16:46:51 raspi2 volumio[1338]: verbose: MPD COMMAND [object Object] Feb 16 16:46:51 raspi2 volumio[1338]: verbose: MPD COMMAND [object Object] Feb 16 16:46:51 raspi2 volumio[1338]: verbose: MPD COMMAND [object Object] Feb 16 16:46:51 raspi2 volumio[1338]: info: Feb 16 16:46:51 raspi2 volumio[1338]: ---------------------------- MPD announces system playlist update Feb 16 16:46:51 raspi2 volumio[1338]: info: Ignoring MPD Status Update Feb 16 16:46:51 raspi2 volumio[1338]: info: Feb 16 16:46:51 raspi2 volumio[1338]: ---------------------------- MPD announces system playlist update Feb 16 16:46:51 raspi2 volumio[1338]: info: Ignoring MPD Status Update Feb 16 16:46:51 raspi2 volumio[1338]: info: [jellyfin-play] Prefetched and added song to MPD queue: Popurrí Musita Feb 16 16:46:51 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 16 16:46:51 raspi2 volumio[1338]: info: ------------------------------ 13ms Feb 16 16:46:51 raspi2 volumio[1338]: info: ------------------------------ 9ms Feb 16 16:46:51 raspi2 volumio[1338]: info: sendMpdCommand consume 1 took 9 milliseconds Feb 16 16:46:56 raspi2 volumio[1338]: info: CoreStateMachine::startPlaybackTimer Feb 16 16:46:56 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:56 raspi2 volumio[1338]: info: CoreStateMachine::pushState Feb 16 16:46:56 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:56 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 16:46:56 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushState Feb 16 16:46:56 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:56 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:56 raspi2 volumio[1338]: info: Feb 16 16:46:56 raspi2 volumio[1338]: ---------------------------- MPD announces system playlist update Feb 16 16:46:56 raspi2 volumio[1338]: info: Ignoring MPD Status Update Feb 16 16:46:56 raspi2 volumio[1338]: info: ControllerMpd::getState Feb 16 16:46:56 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Feb 16 16:46:56 raspi2 volumio[1338]: info: Feb 16 16:46:56 raspi2 volumio[1338]: ---------------------------- MPD announces state update: player Feb 16 16:46:56 raspi2 volumio[1338]: info: ControllerMpd::getState Feb 16 16:46:56 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Feb 16 16:46:57 raspi2 volumio[1338]: info: Feb 16 16:46:57 raspi2 volumio[1338]: ---------------------------- MPD announces system playlist update Feb 16 16:46:57 raspi2 volumio[1338]: info: Ignoring MPD Status Update Feb 16 16:46:57 raspi2 volumio[1338]: info: ControllerMpd::getState Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Feb 16 16:46:57 raspi2 volumio[1338]: info: Feb 16 16:46:57 raspi2 volumio[1338]: ---------------------------- MPD announces state update: player Feb 16 16:46:57 raspi2 volumio[1338]: info: ControllerMpd::getState Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand status Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand status took 1243 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: info: ------------------------------ 1250ms Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand status took 1263 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseState Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseState Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand status took 54 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: info: ------------------------------ 67ms Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand status took 51 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseState Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseState Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Feb 16 16:46:57 raspi2 volumio[1338]: info: ControllerMpd::pushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::servicePushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:57 raspi2 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126,"duration":198,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1271 Kbps","isStreaming":false,"title":"Popurrí Musita","artist":"La Sonora Santanera","album":"70 ANIVERSARIO","uri":"http://100.93.223.101:8096/Audio/c3293a00589a4c52b41b4c20ff1156d8/stream.flac?static=true&mediaSourceId=c3293a00589a4c52b41b4c20ff1156d8&tag=34fc0e3963d8f6d2296bcb72446b1409&t.flac","trackType":"flac"} Feb 16 16:46:57 raspi2 volumio[1338]: verbose: CURRENT POSITION 24 Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::syncState stateService play Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Feb 16 16:46:57 raspi2 volumio[1338]: info: Received an update from plugin. extracting info from payload Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::pushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::pushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushState Feb 16 16:46:57 raspi2 volumio[1338]: info: ------------------------------ 1372ms Feb 16 16:46:57 raspi2 volumio[1338]: info: Pushing Favourites {"favourite":false} Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand playlistinfo took 113 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: info: sendMpdCommand playlistinfo took 116 milliseconds Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Feb 16 16:46:57 raspi2 volumio[1338]: verbose: ControllerMpd::parseTrackInfo Feb 16 16:46:57 raspi2 volumio[1338]: info: ControllerMpd::pushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::servicePushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:57 raspi2 volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":198,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1358 Kbps","isStreaming":false,"title":"Popurrí Musita","artist":"La Sonora Santanera","album":"70 ANIVERSARIO","uri":"http://100.93.223.101:8096/Audio/c3293a00589a4c52b41b4c20ff1156d8/stream.flac?static=true&mediaSourceId=c3293a00589a4c52b41b4c20ff1156d8&tag=34fc0e3963d8f6d2296bcb72446b1409&t.flac","trackType":"flac"} Feb 16 16:46:57 raspi2 volumio[1338]: verbose: CURRENT POSITION 24 Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::syncState stateService play Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::syncState currentStatus play Feb 16 16:46:57 raspi2 volumio[1338]: info: Received an update from plugin. extracting info from payload Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::pushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreStateMachine::pushState Feb 16 16:46:57 raspi2 volumio[1338]: info: CorePlayQueue::getTrack 24 Feb 16 16:46:57 raspi2 volumio[1338]: info: CoreCommandRouter::volumioPushState Feb 16 16:46:58 raspi2 volumio[1338]: info: ------------------------------ 268ms Feb 16 16:46:58 raspi2 volumio[1338]: info: Pushing Favourites {"favourite":false} Feb 16 16:46:58 raspi2 volumio[1338]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Feb 16 16:46:58 raspi2 volumio[1338]: info: FusionDsp - ---- read samplerate from file: 48000 Feb 16 16:46:58 raspi2 volumio[1338]: info: camilladsp stopping service pid 9549... Feb 16 16:46:58 raspi2 volumio[1338]: grep: /proc/9549/cmdline: binary file matches Feb 16 16:46:58 raspi2 volumio[1338]: grep: /proc/9549/cmdline: binary file matches Feb 16 16:46:58 raspi2 volumio[1338]: grep: /proc/9549/cmdline: binary file matches Feb 16 16:46:58 raspi2 volumio[1338]: grep: /proc/9549/cmdline: binary file matches Feb 16 16:46:58 raspi2 volumio[1338]: info: camilladsp service terminated, instance 1 Feb 16 16:46:58 raspi2 volumio[1338]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 16 16:46:58 raspi2 volumio[1338]: info: camilladsp service started and running in background, instance 1 Feb 16 16:46:58 raspi2 volumio[1338]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 16 16:46:58 raspi2 volumio[1338]: info: [jellyfin-play]: Reported 'stop' for song: Pena Negra (at 225289 ms) Feb 16 16:46:59 raspi2 volumio[1338]: info: [jellyfin-play]: Reported 'start' for song: Popurrí Musita (at 862 ms) Feb 16 16:46:59 raspi2 volumio[1338]: info: [jellyfin-play]: Reported 'start' for song: Popurrí Musita (at 105 ms) 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) 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"