Dec 29 12:15:00 volumio nmbd[1219]: [2025/12/29 12:15:00.445755, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 29 12:15:00 volumio nmbd[1219]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.134 for name WORKGROUP<1d>. Dec 29 12:15:00 volumio nmbd[1219]: This response was from IP 192.168.1.158, reporting an IP address of 192.168.1.158. Dec 29 12:15:09 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 29 12:15:11 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 29 12:15:11 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 29 12:15:29 volumio sudo[3159]: pam_unix(sudo:session): session closed for user root Dec 29 12:15:29 volumio volumio[1401]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 29 12:15:29 volumio sudo[3460]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 29 12:15:29 volumio sudo[3460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 12:15:29 volumio sudo[3462]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 12:15:29 volumio sudo[3462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 12:15:29 volumio sudo[3460]: pam_unix(sudo:session): session closed for user root Dec 29 12:15:29 volumio sudo[3462]: pam_unix(sudo:session): session closed for user root Dec 29 12:15:29 volumio volumio[1401]: verbose: New Socket.io Connection to 192.168.1.134 from 192.168.1.192 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::volumioGetState Dec 29 12:15:29 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 12:15:29 volumio volumio[1401]: info: Received Get System Info Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 12:15:29 volumio volumio[1401]: info: Discovery: Getting this device information Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::volumioGetState Dec 29 12:15:29 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 12:15:29 volumio volumio[1401]: info: CoreCommandRouter::volumioGetState Dec 29 12:15:29 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:15:30 volumio volumio[1401]: info: Listing playlists Dec 29 12:15:30 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 12:15:30 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 12:15:30 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 29 12:15:31 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 29 12:15:31 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 12:15:31 volumio volumio[1401]: info: Received Get System Info Dec 29 12:15:31 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 12:15:31 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 12:15:31 volumio volumio[1401]: info: Discovery: Getting this device information Dec 29 12:15:31 volumio volumio[1401]: info: CoreCommandRouter::volumioGetState Dec 29 12:15:31 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:15:31 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 12:15:33 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 12:15:33 volumio volumio[1401]: info: Received Get System Info Dec 29 12:15:33 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 12:15:33 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 12:15:33 volumio volumio[1401]: info: Discovery: Getting this device information Dec 29 12:15:33 volumio volumio[1401]: info: CoreCommandRouter::volumioGetState Dec 29 12:15:33 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:15:33 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 12:15:36 volumio volumio[1401]: info: CoreCommandRouter::volumioGetQueue Dec 29 12:15:36 volumio volumio[1401]: info: CoreStateMachine::getQueue Dec 29 12:15:36 volumio volumio[1401]: info: CorePlayQueue::getQueue Dec 29 12:15:38 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 29 12:15:38 volumio volumio[1401]: info: [jellyfin-browse] browseUri: jellyfin Dec 29 12:15:38 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:39 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 29 12:15:40 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 29 12:15:40 volumio volumio[1401]: info: [jellyfin-browse] browseUri: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846 Dec 29 12:15:40 volumio volumio[1401]: info: [jellyfin-conn] Using previously assigned device Id for davet@3aae36984ea0: 188c5556-e06e-44ae-9b69-d7ad1b20314f Dec 29 12:15:40 volumio volumio[1401]: info: [jellyfin-conn] Login successful: davet@3aae36984ea0 Dec 29 12:15:41 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:41 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:42 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 29 12:15:42 volumio volumio[1401]: info: [jellyfin-browse] browseUri: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb Dec 29 12:15:42 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:42 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:42 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=e620323453fa2de22a381af3d437fdd2 Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=48ce2def903228f937f20f7eb6757d1a Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=9770806aa7c25afd25a0420b91e1ca41 Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=0b1105ffec222836cd4b1e96b99f4af9 Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=8fb71d7ccc3c91de75aea4db7d49467a Dec 29 12:15:42 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=783d82f00e25427ac697add9a653f2ac Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=00bd3e4f8fe18aec60a0169df2fd852f Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=5fcf46fa92ad8d45e99709b32dc22b5c Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=c57cee9b81b7253014d4d34fe1fe6f8f Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=a5640e53b365eb6cea97e7afd1103fbe Dec 29 12:15:42 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:42 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=9266f1b62d80b7bb1d5c662871da3b21 Dec 29 12:15:42 volumio volumio[1401]: info: Exploding uri jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=9266f1b62d80b7bb1d5c662871da3b21 in service jellyfin Dec 29 12:15:42 volumio volumio[1401]: info: [jellyfin-browse] explodeUri: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=9266f1b62d80b7bb1d5c662871da3b21 Dec 29 12:15:44 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 29 12:15:44 volumio volumio[1401]: info: [jellyfin-browse] browseUri: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb Dec 29 12:15:45 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:45 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:15:45 volumio ntpd[1092]: PROTO: 176.58.109.199 unlink local addr 192.168.1.134 -> Dec 29 12:15:48 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 12:15:48 volumio volumio[1401]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 29 12:15:48 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 29 12:15:48 volumio volumio[1401]: info: Received Get System Version Dec 29 12:15:48 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 12:15:48 volumio volumio[1401]: info: Received Get System Info Dec 29 12:15:48 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 12:15:48 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 12:15:48 volumio volumio[1401]: info: Discovery: Getting this device information Dec 29 12:15:48 volumio volumio[1401]: info: CoreCommandRouter::volumioGetState Dec 29 12:15:48 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:15:48 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 12:16:11 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 29 12:16:11 volumio volumio[1401]: info: [jellyfin-browse] browseUri: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9 Dec 29 12:16:11 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=199339f039601f12ab9001222c8179a1 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=3bf6cc6ba1c9e4dfabbb83e4cdca7899 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=62fcd4af6d251ac01d347acf20191edb Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=e467277799fac6a00343cf55672a8a5c Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=76c0fce5a58a1847a0f9c8a67d283901 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=010d2583ac42981525cf018127273553 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=379793dbdca58a8f2d226267e9c24c70 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=bbfe11e1e884ab4e0757a5a71d5986e0 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=9006ef3103d22e79e2177b458d57159b Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=6dfb89391ff8a54d8d3673f06d2762ee Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=1bdf455a0428f4ad593d33f6abe3e6e2 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=bc5b04345ed400c9ef3adeba5359903b Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=e3ca36dd2406ec5ac7e7dd670a1a5345 Dec 29 12:16:11 volumio volumio[1401]: info: Preloading song: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9/song@songId=4cbdcb0c6148135e04d04306b9b0c191 Dec 29 12:16:11 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:16:11 volumio volumio[1401]: info: No valid Plugin REST Endpoint Dec 29 12:16:13 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:16:13 volumio volumio[1401]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::ClearQueue Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::stop Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:13 volumio volumio[1401]: info: CorePlayQueue::clearPlayQueue Dec 29 12:16:13 volumio volumio[1401]: info: CorePlayQueue::saveQueue Dec 29 12:16:13 volumio volumio[1401]: info: CoreCommandRouter::volumioPushQueue Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::addQueueItems Dec 29 12:16:13 volumio volumio[1401]: info: CorePlayQueue::addQueueItems Dec 29 12:16:13 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:16:13 volumio volumio[1401]: info: Adding Item to queue: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9 Dec 29 12:16:13 volumio volumio[1401]: info: Exploding uri jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9 in service jellyfin Dec 29 12:16:13 volumio volumio[1401]: info: [jellyfin-browse] explodeUri: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9 Dec 29 12:16:13 volumio volumio[1401]: info: CoreCommandRouter::volumioPushQueue Dec 29 12:16:13 volumio volumio[1401]: info: CorePlayQueue::saveQueue Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::updateTrackBlock Dec 29 12:16:13 volumio volumio[1401]: info: CorePlayQueue::getTrackBlock Dec 29 12:16:13 volumio volumio[1401]: info: CoreCommandRouter::volumioPlay Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::play index 0 Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::stop Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::play index undefined Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:13 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:13 volumio volumio[1401]: info: CoreStateMachine::startPlaybackTimer Dec 29 12:16:13 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:13 volumio volumio[1401]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/song@songId=199339f039601f12ab9001222c8179a1 Dec 29 12:16:13 volumio volumio[1401]: info: [jellyfin-play] Stream URL for It's My Own Fault: http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61 Dec 29 12:16:13 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 12:16:13 volumio volumio[1401]: info: sendMpdCommand stop took 13 milliseconds Dec 29 12:16:13 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 12:16:13 volumio volumio[1401]: info: sendMpdCommand clear took 0 milliseconds Dec 29 12:16:13 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3" Dec 29 12:16:13 volumio volumio[1401]: info: Dec 29 12:16:13 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:13 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:13 volumio volumio[1401]: info: Dec 29 12:16:13 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:13 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio mpd[1771]: exception: No such playlist Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:14 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3" Dec 29 12:16:14 volumio volumio[1401]: error: updateQueue error: null Dec 29 12:16:14 volumio volumio[1401]: error: updateQueue error: null Dec 29 12:16:14 volumio volumio[1401]: error: updateQueue error: null Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 341ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 339ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 3ms Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand addid "http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3" took 3 milliseconds Dec 29 12:16:14 volumio volumio[1401]: verbose: MPD COMMAND [object Object] Dec 29 12:16:14 volumio volumio[1401]: verbose: MPD COMMAND [object Object] Dec 29 12:16:14 volumio volumio[1401]: verbose: MPD COMMAND [object Object] Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:14 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:14 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:14 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand play Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:14 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 7ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 7ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 6ms Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:14 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:14 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand play took 10 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 9ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 5ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 4ms Dec 29 12:16:14 volumio mpd[1771]: [src/libmpg123/parse.c:do_readahead():1122] error: cannot seek! Dec 29 12:16:14 volumio mpd[1771]: mpg123: mpg123_getformat() failed: A generic mpg123 error. Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio mpd[1771]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Dec 29 12:16:14 volumio mpd[1771]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Dec 29 12:16:14 volumio mpd[1771]: player: problems opening audio device while playing "http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3" Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 25 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 35 milliseconds Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 35 milliseconds Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 35 milliseconds Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: info: Dec 29 12:16:14 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 52 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand clearerror took 46 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 47 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 42 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand clearerror took 36 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand clearerror took 35 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand clearerror took 33 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand status took 19 milliseconds Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::servicePushState Dec 29 12:16:14 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:14 volumio volumio[1401]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's My Own Fault","artist":"John Lee Hooker","album":"Hobo Blues","uri":"http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3","trackType":"mp3"} Dec 29 12:16:14 volumio volumio[1401]: verbose: CURRENT POSITION 0 Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState stateService pause Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState currentStatus stop Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::volumioPushState Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::servicePushState Dec 29 12:16:14 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:14 volumio volumio[1401]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's My Own Fault","artist":"John Lee Hooker","album":"Hobo Blues","uri":"http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3","trackType":"mp3"} Dec 29 12:16:14 volumio volumio[1401]: verbose: CURRENT POSITION 0 Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState stateService pause Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState currentStatus stop Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::volumioPushState Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::servicePushState Dec 29 12:16:14 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:14 volumio volumio[1401]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's My Own Fault","artist":"John Lee Hooker","album":"Hobo Blues","uri":"http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3","trackType":"mp3"} Dec 29 12:16:14 volumio volumio[1401]: verbose: CURRENT POSITION 0 Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState stateService pause Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState currentStatus stop Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::volumioPushState Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 130ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 128ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 125ms Dec 29 12:16:14 volumio volumio[1401]: info: Pushing Favourites {"favourite":false} Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand clearerror took 71 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 67 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 67 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 64 milliseconds Dec 29 12:16:14 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 61 milliseconds Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::servicePushState Dec 29 12:16:14 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:14 volumio volumio[1401]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's My Own Fault","artist":"John Lee Hooker","album":"Hobo Blues","uri":"http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3","trackType":"mp3"} Dec 29 12:16:14 volumio volumio[1401]: verbose: CURRENT POSITION 0 Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState stateService pause Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState currentStatus stop Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::volumioPushState Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::servicePushState Dec 29 12:16:14 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:14 volumio volumio[1401]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's My Own Fault","artist":"John Lee Hooker","album":"Hobo Blues","uri":"http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3","trackType":"mp3"} Dec 29 12:16:14 volumio volumio[1401]: verbose: CURRENT POSITION 0 Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState stateService pause Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState currentStatus stop Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::volumioPushState Dec 29 12:16:14 volumio volumio[1401]: info: ControllerMpd::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::servicePushState Dec 29 12:16:14 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:14 volumio volumio[1401]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's My Own Fault","artist":"John Lee Hooker","album":"Hobo Blues","uri":"http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3","trackType":"mp3"} Dec 29 12:16:14 volumio volumio[1401]: verbose: CURRENT POSITION 0 Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState stateService pause Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::syncState currentStatus stop Dec 29 12:16:14 volumio volumio[1401]: info: CoreStateMachine::pushState Dec 29 12:16:14 volumio volumio[1401]: info: CoreCommandRouter::volumioPushState Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 173ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 165ms Dec 29 12:16:14 volumio volumio[1401]: info: ------------------------------ 139ms Dec 29 12:16:14 volumio volumio[1401]: info: Pushing Favourites {"favourite":false} Dec 29 12:16:14 volumio volumio[1401]: info: [jellyfin-play]: Mark song It's My Own Fault as played by davet. Dec 29 12:16:14 volumio volumio[1401]: info: [jellyfin-play]: Reported 'pause' for song: It's My Own Fault (at 0 ms) Dec 29 12:16:20 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:16:20 volumio volumio[1401]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::ClearQueue Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::stop Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::clearPlayQueue Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::saveQueue Dec 29 12:16:20 volumio volumio[1401]: info: CoreCommandRouter::volumioPushQueue Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::addQueueItems Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::addQueueItems Dec 29 12:16:20 volumio volumio[1401]: info: Preload queue cleared Dec 29 12:16:20 volumio volumio[1401]: info: Adding Item to queue: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9 Dec 29 12:16:20 volumio volumio[1401]: info: Using cached record of: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb/songs@albumId=b56a1cbe2169dfbd0f23479d02364df9 Dec 29 12:16:20 volumio volumio[1401]: info: CoreCommandRouter::volumioPushQueue Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::saveQueue Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::updateTrackBlock Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::getTrackBlock Dec 29 12:16:20 volumio volumio[1401]: info: CoreCommandRouter::volumioPlay Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::play index 0 Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::stop Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::play index undefined Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::startPlaybackTimer Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:20 volumio volumio[1401]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/song@songId=199339f039601f12ab9001222c8179a1 Dec 29 12:16:20 volumio volumio[1401]: info: [jellyfin-play] Stream URL for It's My Own Fault: http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61 Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 12:16:20 volumio mpd[1771]: player: played "http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3" Dec 29 12:16:20 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:20 volumio volumio[1401]: info: Dec 29 12:16:20 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:20 volumio volumio[1401]: info: sendMpdCommand stop took 4 milliseconds Dec 29 12:16:20 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 12:16:20 volumio volumio[1401]: info: Dec 29 12:16:20 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:20 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:20 volumio volumio[1401]: info: Dec 29 12:16:20 volumio volumio[1401]: ---------------------------- MPD announces system playlist update Dec 29 12:16:20 volumio volumio[1401]: info: Ignoring MPD Status Update Dec 29 12:16:20 volumio volumio[1401]: info: sendMpdCommand status took 4 milliseconds Dec 29 12:16:20 volumio volumio[1401]: info: sendMpdCommand status took 3 milliseconds Dec 29 12:16:20 volumio volumio[1401]: info: sendMpdCommand clear took 4 milliseconds Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.135:34271/Audio/199339f039601f12ab9001222c8179a1/stream.mp3?static=true&mediaSourceId=199339f039601f12ab9001222c8179a1&tag=6a77ea6eded86dd04075da6ecbfbec61&t.mp3" Dec 29 12:16:20 volumio volumio[1401]: info: Dec 29 12:16:20 volumio volumio[1401]: ---------------------------- MPD announces state update: player Dec 29 12:16:20 volumio volumio[1401]: info: ControllerMpd::getState Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::sendMpdCommand status Dec 29 12:16:20 volumio volumio[1401]: error: updateQueue error: null Dec 29 12:16:20 volumio volumio[1401]: info: sendMpdCommand status took 7 milliseconds Dec 29 12:16:20 volumio volumio[1401]: info: ------------------------------ 7ms Dec 29 12:16:20 volumio volumio[1401]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::parseState Dec 29 12:16:20 volumio volumio[1401]: verbose: ControllerMpd::parseTrackInfo Dec 29 12:16:20 volumio volumio[1401]: info: ControllerMpd::pushState Dec 29 12:16:20 volumio volumio[1401]: info: CoreCommandRouter::servicePushState Dec 29 12:16:20 volumio volumio[1401]: info: CoreStateMachine::pushState Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:20 volumio volumio[1401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 12:16:20 volumio volumio[1401]: info: CoreCommandRouter::volumioPushState Dec 29 12:16:20 volumio volumio[1401]: info: CorePlayQueue::getTrack 0 Dec 29 12:16:20 volumio volumio[1401]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 29 12:16:20 volumio mpd[1771]: exception: No such playlist Dec 29 12:16:20 volumio volumio[1401]: info: ------------------------------ 22ms Dec 29 12:16:20 volumio volumio[1401]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 12:16:20 volumio volumio[1401]: TypeError: Cannot read properties of undefined (reading 'split') Dec 29 12:16:20 volumio volumio[1401]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Dec 29 12:16:20 volumio volumio[1401]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 29 12:16:20 volumio volumio[1401]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 29 12:16:20 volumio volumio[1401]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 12:16:22 volumio sudo[3581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 12:15' Dec 29 12:16:22 volumio sudo[3581]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"