Aug 29 20:26:00 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 29 20:26:00 volumio volumio[1404]: info: [jellyfin-browse] browseUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84 Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/UserViews?userId=94a2c620f30f4a98b18146d8063834f1 Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"78c87f6dc2424e837e7db15452f12478","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"69b9a4a30fa1ebe7a58dbeab78a90c58","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=69b9a4a30fa1ebe7a58dbeab78a90c58&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=82aa208295e7be33dca97b36e1d84aa4&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:00 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=78c87f6dc2424e837e7db15452f12478&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:00 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:00 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:00 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:06 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 29 20:26:06 volumio volumio[1404]: info: [jellyfin-browse] browseUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=82aa208295e7be33dca97b36e1d84aa4 Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":5,"sortBy":["DatePlayed","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":5,"sortBy":["PlayCount","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"]} Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"filters":["IsFavorite"],"includeItemTypes":["Audio"]} Aug 29 20:26:06 volumio launch-soloist.sh[7672]: 2026-08-29 20:26:06.371: playing spotify:track:0aTiUssEOy0Mt69bsavj6K "Descending" by "TOOL" [6:20 / 13:37] Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=82aa208295e7be33dca97b36e1d84aa4&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=82aa208295e7be33dca97b36e1d84aa4&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=DatePlayed&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=82aa208295e7be33dca97b36e1d84aa4&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=PlayCount&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Ascending&parentId=82aa208295e7be33dca97b36e1d84aa4&includeItemTypes=MusicAlbum&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Ascending&parentId=82aa208295e7be33dca97b36e1d84aa4&fields=MediaSources&includeItemTypes=Audio&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:06 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Artists?startIndex=0&limit=5&parentId=82aa208295e7be33dca97b36e1d84aa4&filters=IsFavorite&imageTypeLimit=1&enableImageTypes=Primary&userId=94a2c620f30f4a98b18146d8063834f1&sortBy=SortName&sortOrder=Ascending Aug 29 20:26:06 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:06 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:13 volumio bluealsa[986]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_7B_8E_3B_FF_83_49, ...) Aug 29 20:26:16 volumio launch-soloist.sh[7672]: 2026-08-29 20:26:16.372: playing spotify:track:0aTiUssEOy0Mt69bsavj6K "Descending" by "TOOL" [6:30 / 13:37] Aug 29 20:26:21 volumio sudo[13918]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 20:26:21 volumio sudo[13916]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 20:26:21 volumio sudo[13916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:26:21 volumio sudo[13918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:26:21 volumio sudo[13916]: pam_unix(sudo:session): session closed for user root Aug 29 20:26:21 volumio sudo[13918]: pam_unix(sudo:session): session closed for user root Aug 29 20:26:21 volumio volumio[1404]: verbose: New Socket.io Connection to 192.168.31.71 from 192.168.31.87 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::volumioGetQueue Aug 29 20:26:21 volumio volumio[1404]: info: CoreStateMachine::getQueue Aug 29 20:26:21 volumio volumio[1404]: info: CorePlayQueue::getQueue Aug 29 20:26:21 volumio volumio[1404]: info: Listing playlists Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 20:26:21 volumio volumio[1404]: info: Received Get System Info Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:26:21 volumio volumio[1404]: info: Discovery: Getting this device information Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 20:26:21 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 20:26:22 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 29 20:26:22 volumio volumio[1404]: info: [jellyfin-browse] browseUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=82aa208295e7be33dca97b36e1d84aa4/albums@parentId=82aa208295e7be33dca97b36e1d84aa4@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@noExplode=1 Aug 29 20:26:22 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"82aa208295e7be33dca97b36e1d84aa4","startIndex":0,"limit":47,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:22 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=47&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=82aa208295e7be33dca97b36e1d84aa4&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:22 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:23 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 20:26:23 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 20:26:23 volumio volumio[1404]: info: Received Get System Info Aug 29 20:26:23 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:26:23 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:26:23 volumio volumio[1404]: info: Discovery: Getting this device information Aug 29 20:26:23 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Aug 29 20:26:23 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:26:24 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 20:26:24 volumio volumio[1404]: info: Received Get System Info Aug 29 20:26:24 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:26:24 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:26:24 volumio volumio[1404]: info: Discovery: Getting this device information Aug 29 20:26:24 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Aug 29 20:26:24 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:26:26 volumio launch-soloist.sh[7672]: 2026-08-29 20:26:26.372: playing spotify:track:0aTiUssEOy0Mt69bsavj6K "Descending" by "TOOL" [6:40 / 13:37] Aug 29 20:26:28 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 29 20:26:28 volumio volumio[1404]: info: [jellyfin-browse] browseUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58 Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"69b9a4a30fa1ebe7a58dbeab78a90c58","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"69b9a4a30fa1ebe7a58dbeab78a90c58","startIndex":0,"limit":5,"sortBy":["DatePlayed","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"69b9a4a30fa1ebe7a58dbeab78a90c58","startIndex":0,"limit":5,"sortBy":["PlayCount","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"69b9a4a30fa1ebe7a58dbeab78a90c58","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"]} Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"69b9a4a30fa1ebe7a58dbeab78a90c58","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"],"includeItemTypes":["MusicAlbum"]} Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"69b9a4a30fa1ebe7a58dbeab78a90c58","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"filters":["IsFavorite"],"includeItemTypes":["Audio"]} Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=69b9a4a30fa1ebe7a58dbeab78a90c58&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=DatePlayed&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=69b9a4a30fa1ebe7a58dbeab78a90c58&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=PlayCount&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=69b9a4a30fa1ebe7a58dbeab78a90c58&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Artists?startIndex=0&limit=5&parentId=69b9a4a30fa1ebe7a58dbeab78a90c58&filters=IsFavorite&imageTypeLimit=1&enableImageTypes=Primary&userId=94a2c620f30f4a98b18146d8063834f1&sortBy=SortName&sortOrder=Ascending Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Ascending&parentId=69b9a4a30fa1ebe7a58dbeab78a90c58&fields=MediaSources&includeItemTypes=Audio&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:28 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&limit=5&recursive=true&sortOrder=Ascending&parentId=69b9a4a30fa1ebe7a58dbeab78a90c58&includeItemTypes=MusicAlbum&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:28 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:28 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:31 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 20:26:34 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 29 20:26:34 volumio volumio[1404]: info: [jellyfin-browse] browseUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb Aug 29 20:26:34 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"94a2c620f30f4a98b18146d8063834f1","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"b3ce98f3f95f8850b4cc07a9fc7aeacb","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Aug 29 20:26:35 volumio volumio[1404]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:8096/Items?userId=94a2c620f30f4a98b18146d8063834f1&startIndex=0&recursive=true&sortOrder=Ascending&parentId=b3ce98f3f95f8850b4cc07a9fc7aeacb&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 29 20:26:35 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=f2e5125b49b6de2edaf7fea8b8906c42 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=ebdd469f4fcd74358ee0e87124e87ab9 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=4279581cd0fc762a3f8526b3a9280552 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01f0aeef4ab1c177aa497c5ab1d6153f Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=328df54cc3ccd5120871ed35accaa460 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=8b061ac4330a933baf3c8277f1d0e0fc Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=c3d5479966e084e23d80ce5596d64438 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=5d7872a484cf1252731721e842071561 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=d1c179d69921d7b26366ab52fe9e8e30 Aug 29 20:26:35 volumio volumio[1404]: info: Preloading song: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=75d7a7650b36351b525a5159b0e98b30 Aug 29 20:26:35 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:36 volumio launch-soloist.sh[7672]: 2026-08-29 20:26:36.371: playing spotify:track:0aTiUssEOy0Mt69bsavj6K "Descending" by "TOOL" [6:50 / 13:37] Aug 29 20:26:37 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 20:26:37 volumio volumio[1404]: info: CoreStateMachine::ClearQueue Aug 29 20:26:37 volumio volumio[1404]: info: CoreStateMachine::stop Aug 29 20:26:37 volumio volumio[1404]: info: CoreStateMachine::serviceStop Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::serviceStop Aug 29 20:26:37 volumio volumio[1404]: info: SoloistConnect: yielding playback Aug 29 20:26:37 volumio launch-soloist.sh[7672]: 2026-08-29 20:26:37.451: paused spotify:track:0aTiUssEOy0Mt69bsavj6K "Descending" by "TOOL" [6:52 / 13:37] Aug 29 20:26:37 volumio volumio[1404]: info: CorePlayQueue::clearPlayQueue Aug 29 20:26:37 volumio volumio[1404]: info: CorePlayQueue::saveQueue Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:26:37 volumio volumio[1404]: info: CoreStateMachine::addQueueItems Aug 29 20:26:37 volumio volumio[1404]: info: CorePlayQueue::addQueueItems Aug 29 20:26:37 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:37 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:26:37 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 in service jellyfin Aug 29 20:26:37 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::servicePushState Aug 29 20:26:37 volumio volumio[1404]: info: CoreStateMachine::pushState Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::volumioPushState Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::servicePushState Aug 29 20:26:37 volumio volumio[1404]: info: CoreStateMachine::pushState Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::volumioPushState Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:26:37 volumio volumio[1404]: info: CorePlayQueue::saveQueue Aug 29 20:26:37 volumio volumio[1404]: info: CoreStateMachine::updateTrackBlock Aug 29 20:26:37 volumio volumio[1404]: info: CorePlayQueue::getTrackBlock Aug 29 20:26:37 volumio volumio[1404]: info: CoreCommandRouter::volumioPlay Aug 29 20:26:37 volumio volumio[1404]: verbose: UNSET VOLATILE: Service: soloist_connect Aug 29 20:26:38 volumio volumio[1404]: verbose: UNSET VOLATILE: Service: soloist_connect Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::play index 0 Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::addQueueItems Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::addQueueItems Aug 29 20:26:38 volumio volumio[1404]: info: Preload queue cleared Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=f2e5125b49b6de2edaf7fea8b8906c42 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=f2e5125b49b6de2edaf7fea8b8906c42 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=f2e5125b49b6de2edaf7fea8b8906c42 Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=ebdd469f4fcd74358ee0e87124e87ab9 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=ebdd469f4fcd74358ee0e87124e87ab9 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=ebdd469f4fcd74358ee0e87124e87ab9 Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=4279581cd0fc762a3f8526b3a9280552 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=4279581cd0fc762a3f8526b3a9280552 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=4279581cd0fc762a3f8526b3a9280552 Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01f0aeef4ab1c177aa497c5ab1d6153f Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01f0aeef4ab1c177aa497c5ab1d6153f in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01f0aeef4ab1c177aa497c5ab1d6153f Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=328df54cc3ccd5120871ed35accaa460 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=328df54cc3ccd5120871ed35accaa460 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=328df54cc3ccd5120871ed35accaa460 Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=8b061ac4330a933baf3c8277f1d0e0fc Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=8b061ac4330a933baf3c8277f1d0e0fc in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=8b061ac4330a933baf3c8277f1d0e0fc Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=c3d5479966e084e23d80ce5596d64438 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=c3d5479966e084e23d80ce5596d64438 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=c3d5479966e084e23d80ce5596d64438 Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=5d7872a484cf1252731721e842071561 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=5d7872a484cf1252731721e842071561 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=5d7872a484cf1252731721e842071561 Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=d1c179d69921d7b26366ab52fe9e8e30 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=d1c179d69921d7b26366ab52fe9e8e30 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=d1c179d69921d7b26366ab52fe9e8e30 Aug 29 20:26:38 volumio volumio[1404]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=75d7a7650b36351b525a5159b0e98b30 Aug 29 20:26:38 volumio volumio[1404]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=75d7a7650b36351b525a5159b0e98b30 in service jellyfin Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=75d7a7650b36351b525a5159b0e98b30 Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::stop Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::updateTrackBlock Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::getTrackBlock Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::stPlaybackTimer Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::pushState Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Aug 29 20:26:38 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:26:38 volumio volumio[1404]: info: CoreCommandRouter::volumioPushState Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::serviceStop Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Aug 29 20:26:38 volumio volumio[1404]: info: ControllerMpd::stop Aug 29 20:26:38 volumio volumio[1404]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 20:26:38 volumio volumio[1404]: info: sendMpdCommand stop took 96 milliseconds Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::play index undefined Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::getTrack 0 Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::getTrack 0 Aug 29 20:26:38 volumio volumio[1404]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:26:38 volumio volumio[1404]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::saveQueue Aug 29 20:26:38 volumio volumio[1404]: info: CoreStateMachine::updateTrackBlock Aug 29 20:26:38 volumio volumio[1404]: info: CorePlayQueue::getTrackBlock Aug 29 20:26:38 volumio volumio[1404]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:26:38 volumio volumio[1404]: Error: No media streams found for song 01 Vicarious Aug 29 20:26:38 volumio volumio[1404]: at PlayController._PlayController_getStreamUrl (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:241:15) Aug 29 20:26:38 volumio volumio[1404]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:78:234) Aug 29 20:26:38 volumio volumio[1404]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 29 20:26:38 volumio volumio[1404]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:26:39 volumio sudo[14042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 20:25' Aug 29 20:26:39 volumio sudo[14042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:26:40 volumio sudo[14042]: pam_unix(sudo:session): session closed for user root Aug 29 20:26:40 volumio volumio5-onboarding[1738]: time=2026-08-29T20:26:40.733+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 29 20:26:40 volumio volumio5-onboarding[1738]: time=2026-08-29T20:26:40.747+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 20:26:40 volumio volumio-remote-updater[706]: [2026-08-29 20:26:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 20:26:40 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:26:40 volumio volumio-remote-updater[706]: [2026-08-29 20:26:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 20:26:40 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 20:26:40 volumio systemd[1]: volumio.service: Consumed 9min 49.026s CPU time. Aug 29 20:26:40 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 29 20:26:40 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Aug 29 20:26:40 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 29 20:26:40 volumio systemd[1]: volumio.service: Consumed 9min 49.026s CPU time. Aug 29 20:26:40 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Aug 29 20:26:40 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Aug 29 20:26:41 volumio volumio5-onboarding[1738]: time=2026-08-29T20:26:41.751+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 20:26:42 volumio volumio5-onboarding[1738]: time=2026-08-29T20:26:42.757+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 20:26:43 volumio volumio5-onboarding[1738]: time=2026-08-29T20:26:43.760+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 20:26:44 volumio bluealsa[986]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_7B_8E_3B_FF_83_49, ...) Aug 29 20:26:45 volumio volumio-remote-updater[706]: [2026-08-29 20:26:45] [connect] Successful connection Aug 29 20:26:45 volumio volumio[14078]: info: ------------------------------------------- Aug 29 20:26:45 volumio volumio[14078]: info: ----- Volumio3 ---- Aug 29 20:26:45 volumio volumio[14078]: info: ------------------------------------------- Aug 29 20:26:45 volumio volumio[14078]: info: ----- System startup ---- Aug 29 20:26:45 volumio volumio[14078]: info: ------------------------------------------- Aug 29 20:26:47 volumio volumio[14078]: info: MYVOLUMIO Environment detected Aug 29 20:26:47 volumio volumio[14078]: info: Plugin folders cleanup Aug 29 20:26:47 volumio volumio[14078]: info: Scanning into folder /volumio/app/plugins/ Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category audio_interface Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category miscellanea Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category music_service Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category plugins.json Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category system_controller Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category user_interface Aug 29 20:26:47 volumio volumio[14078]: info: Scanning into folder /data/plugins/ Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category audio_interface Aug 29 20:26:47 volumio volumio[14078]: info: Scanning category music_service Aug 29 20:26:48 volumio volumio[14078]: info: Plugin folders cleanup completed Aug 29 20:26:48 volumio volumio[14078]: info: ------------------------------------------- Aug 29 20:26:48 volumio volumio[14078]: info: ----- Core plugins startup ---- Aug 29 20:26:48 volumio volumio[14078]: info: ------------------------------------------- Aug 29 20:26:48 volumio volumio[14078]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 20:26:48 volumio volumio[14078]: info: Adding plugin upnp to MyMusic Plugins Aug 29 20:26:48 volumio volumio[14078]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 20:26:48 volumio volumio[14078]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 20:26:48 volumio volumio[14078]: info: Loading plugins from folder /data/plugins/ Aug 29 20:26:48 volumio volumio[14078]: info: Loading plugin "system"... Aug 29 20:26:48 volumio volumio[14078]: info: Loading plugin "appearance"... Aug 29 20:26:50 volumio volumio[14078]: info: Loading plugin "network"... Aug 29 20:26:50 volumio volumio[14078]: info: Refreshing Cached IP Addresses Aug 29 20:26:50 volumio sudo[14102]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 20:26:50 volumio sudo[14102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:26:50 volumio sudo[14104]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 20:26:50 volumio sudo[14102]: pam_unix(sudo:session): session closed for user root Aug 29 20:26:50 volumio sudo[14104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:26:50 volumio volumio[14078]: info: Loading plugin "services"... Aug 29 20:26:50 volumio sudo[14104]: pam_unix(sudo:session): session closed for user root Aug 29 20:26:50 volumio volumio[14078]: info: Loading plugin "volumio5onboarding"... Aug 29 20:26:50 volumio sudo[14112]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 20:26:50 volumio sudo[14112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:26:50 volumio volumio[14078]: info: Loading plugin "alsa_controller"... Aug 29 20:26:51 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:26:51 volumio volumio[14078]: info: Loading plugin "wizard"... Aug 29 20:26:51 volumio volumio[14078]: info: Loading plugin "networkfs"... Aug 29 20:26:51 volumio volumio[14078]: info: Starting Udev Watcher for removable devices Aug 29 20:26:51 volumio volumio[14078]: info: Ignoring mount for partition: boot Aug 29 20:26:51 volumio volumio[14078]: info: Ignoring mount for partition: volumio Aug 29 20:26:51 volumio volumio[14078]: info: Ignoring mount for partition: volumio_data Aug 29 20:26:51 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:26:51 volumio volumio[14078]: info: Loading plugin "volumio_command_line_client"... Aug 29 20:26:51 volumio volumio[14078]: info: Loading plugin "upnp"... Aug 29 20:26:51 volumio volumio[14078]: info: [1788028011295] Starting Upmpd Daemon Aug 29 20:26:51 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:26:51 volumio volumio[14078]: info: Loading plugin "my_music"... Aug 29 20:26:51 volumio volumio[14078]: info: Loading plugin "mpd"... Aug 29 20:26:51 volumio sudo[14112]: pam_unix(sudo:session): session closed for user root Aug 29 20:26:52 volumio volumio[14078]: info: Loading plugin "upnp_browser"... Aug 29 20:26:54 volumio volumio5-onboarding[1738]: time=2026-08-29T20:26:54.763+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:34920->127.0.0.1:3000: i/o timeout" Aug 29 20:26:55 volumio volumio[14078]: info: Starting UPNP Browser Aug 29 20:26:55 volumio volumio[14078]: info: Loading plugin "alarm-clock"... Aug 29 20:26:55 volumio volumio[14078]: info: Loading plugin "airplay_emulation"... Aug 29 20:26:55 volumio volumio[14078]: info: Starting Shairport Sync Aug 29 20:26:55 volumio volumio[14078]: info: Loading plugin "last_100"... Aug 29 20:26:55 volumio volumio[14078]: info: Loading plugin "webradio"... Aug 29 20:26:56 volumio volumio[14078]: info: Loading plugin "i2s_dacs"... Aug 29 20:26:56 volumio volumio[14078]: info: I2S DAC not set, start Auto-detection Aug 29 20:26:56 volumio volumio[14078]: info: Loading plugin "volumiodiscovery"... Aug 29 20:26:56 volumio volumio[14078]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 20:26:56 volumio volumio[14078]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:26:56 volumio volumio[14078]: *** WARNING *** For more information see Aug 29 20:26:56 volumio volumio[14078]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 20:26:56 volumio volumio[14078]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:26:56 volumio volumio[14078]: *** WARNING *** For more information see Aug 29 20:26:56 volumio node[14078]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 20:26:56 volumio node[14078]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:26:56 volumio node[14078]: *** WARNING *** For more information see Aug 29 20:26:56 volumio node[14078]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 20:26:56 volumio node[14078]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:26:56 volumio node[14078]: *** WARNING *** For more information see Aug 29 20:26:56 volumio volumio[14078]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 20:26:56 volumio volumio[14078]: info: Discovery: Started advertising with name: Volumio Aug 29 20:26:56 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:26:56 volumio volumio[14078]: info: Loading plugin "jellyfin"... Aug 29 20:26:58 volumio volumio-remote-updater[706]: [2026-08-29 20:26:58] [connect] Successful connection Aug 29 20:26:58 volumio volumio[14078]: info: Loading plugin "outputs"... Aug 29 20:26:58 volumio volumio[14078]: info: Loading plugin "albumart"... Aug 29 20:26:58 volumio volumio[14078]: info: Plugin example_plugin is not enabled Aug 29 20:26:58 volumio volumio[14078]: info: Loading plugin "inputs"... Aug 29 20:26:58 volumio volumio[14078]: info: Loading plugin "updater_comm"... Aug 29 20:26:58 volumio volumio[14078]: info: Plugin mpdemulation is not enabled Aug 29 20:26:58 volumio volumio[14078]: info: Loading plugin "rest_api"... Aug 29 20:26:58 volumio volumio[14078]: info: Loading plugin "websocket"... Aug 29 20:26:58 volumio volumio[14078]: info: Starting Socket.io Server version 1.7.4 Aug 29 20:26:58 volumio volumio[14078]: info: Loading plugin "soloist_connect"... Aug 29 20:26:59 volumio volumio[14078]: info: Loading i18n strings for locale en Aug 29 20:26:59 volumio volumio[14135]: Forking 3 albumart workers Aug 29 20:26:59 volumio volumio[14078]: Updating browse sources language Aug 29 20:26:59 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::initPlayerControls Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: Express server listening on port 3000 Aug 29 20:27:00 volumio volumio[14078]: [Metrics] WebUI: 15s 597.87ms Aug 29 20:27:00 volumio volumio[14078]: info: CoreStateMachine::resetVolumioState Aug 29 20:27:00 volumio volumio[14078]: info: CoreStateMachine::getcurrentVolume Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 20:27:00 volumio volumio[14078]: info: CoreStateMachine::pushState Aug 29 20:27:00 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::volumioPushState Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:00 volumio volumio[14078]: info: Volumio Network Manager: Network status updated: 2 Aug 29 20:27:00 volumio volumio[14078]: info: Reloading queue from file Aug 29 20:27:00 volumio volumio[14078]: verbose: New Socket.io Connection to 192.168.31.71 from 192.168.31.87 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 20:27:00 volumio volumio[14078]: info: Setting Device type: Raspberry PI Aug 29 20:27:00 volumio volumio[14078]: info: CoreStateMachine::setRepeat null single undefined Aug 29 20:27:00 volumio volumio[14078]: info: CoreStateMachine::pushState Aug 29 20:27:00 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::volumioPushState Aug 29 20:27:00 volumio volumio[14078]: info: CoreStateMachine::setRandom null Aug 29 20:27:00 volumio volumio[14078]: info: CoreStateMachine::pushState Aug 29 20:27:00 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:00 volumio volumio[14078]: info: CoreCommandRouter::volumioPushState Aug 29 20:27:00 volumio volumio[14078]: verbose: New Socket.io Connection to 192.168.31.71 from 192.168.31.87 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 20:27:00 volumio volumio[14078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 29 20:27:01 volumio volumio[14078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 29 20:27:01 volumio volumio[14078]: info: Received Get System Info Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:01 volumio volumio[14078]: info: Discovery: Getting this device information Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:01 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 20:27:01 volumio volumio-remote-updater[706]: [2026-08-29 20:27:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788028018 101 Aug 29 20:27:01 volumio volumio[14078]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Aug 29 20:27:01 volumio volumio[14078]: info: Discovery: adding bea9bc0b-e08a-47f5-b4e5-a0285ad6c485 Aug 29 20:27:01 volumio volumio[14078]: info: Discovery: Found device Volumio Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:01 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:01 volumio volumio[14078]: info: Discovery: this is already registered, bea9bc0b-e08a-47f5-b4e5-a0285ad6c485 Aug 29 20:27:01 volumio volumio[14078]: info: Discovery: Found device Volumio Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:01 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:01 volumio volumio[14078]: info: Completed loading Core Plugins Aug 29 20:27:01 volumio volumio[14078]: info: Preparing to generate the ALSA configuration file Aug 29 20:27:01 volumio sudo[14203]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 20:27:01 volumio sudo[14203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:01 volumio sudo[14203]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:01 volumio sudo[14205]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 20:27:01 volumio sudo[14205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:01 volumio sudo[14205]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:01 volumio volumio[14078]: info: Asound.conf file unchanged, so no further update is needed Aug 29 20:27:01 volumio volumio[14078]: info: Output device has changed, restarting MPD Aug 29 20:27:01 volumio volumio[14078]: info: Output device has changed, restarting Shairport Sync Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:01 volumio volumio[14078]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 20:27:01 volumio sudo[14209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 20:27:01 volumio volumio[14078]: info: ___________ START PLUGINS ___________ Aug 29 20:27:01 volumio sudo[14209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:01 volumio sudo[14209]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:01 volumio sudo[14211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 20:27:01 volumio sudo[14211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:01 volumio volumio[14078]: info: ControllerMpd::onStart: Initializing MPD Aug 29 20:27:01 volumio volumio[14078]: info: Creating MPD Configuration file Aug 29 20:27:01 volumio sudo[14219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 29 20:27:01 volumio sudo[14219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 20:27:01 volumio sudo[14221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 20:27:01 volumio sudo[14221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:01 volumio volumio[14078]: info: [1788028021921] CoreMusicLibrary::Adding element Media Servers Aug 29 20:27:01 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:01 volumio sudo[14221]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:01 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 20:27:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 20:27:01 volumio systemd[1]: mpd.service: Consumed 6.491s CPU time. Aug 29 20:27:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 20:27:02 volumio volumio[14078]: info: UPNP Browser: Client initialized successfully Aug 29 20:27:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 20:27:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 20:27:02 volumio sudo[14224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 20:27:02 volumio sudo[14224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 20:27:02 volumio volumio5-onboarding[1738]: time=2026-08-29T20:27:02.107+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 20:27:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 20:27:02 volumio volumio[14078]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:02 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 20:27:02 volumio sudo[14219]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:02 volumio volumio[14078]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 20:27:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:02 volumio volumio[14078]: info: [1788028022215] CoreMusicLibrary::Adding element Last_100 Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:02 volumio volumio[14078]: info: [1788028022225] CoreMusicLibrary::Adding element Webradio Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:27:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 20:27:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 20:27:02 volumio volumio[14078]: info: Initializing BBC Radios Aug 29 20:27:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 20:27:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:02 volumio volumio[14078]: info: Discovery: Getting this device information Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:02 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:02 volumio volumio[14078]: info: [1788028022575] CoreMusicLibrary::Adding element Jellyfin Aug 29 20:27:02 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:02 volumio volumio[14078]: Cannot find translation for source Jellyfin Aug 29 20:27:02 volumio volumio[14078]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.31.71","id":"bea9bc0b-e08a-47f5-b4e5-a0285ad6c485","name":"Volumio","serviceName":"Volumio","state":{"albumart":"http://192.168.31.71/albumart?sourceicon=music_service%2Fjellyfin%2Fdist%2Fassets%2Fimages%2Fsong.png","artist":"","mute":false,"status":"stop","track":"01 Vicarious","volume":100},"type":"device"} Aug 29 20:27:02 volumio volumio[14078]: info: SoloistConnect: plugin=0.8.1 shim=0.2.9 Aug 29 20:27:02 volumio volumio[14078]: info: Volumio Calling Home Aug 29 20:27:02 volumio sudo[14244]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 20:27:02 volumio sudo[14244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 20:27:02 volumio sudo[14244]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:02 volumio volumio[14146]: Starting albumart workers Aug 29 20:27:03 volumio volumio[14145]: Starting albumart workers Aug 29 20:27:03 volumio volumio[14147]: Starting albumart workers Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , soloistMeteringWanted Aug 29 20:27:04 volumio volumio[14078]: info: SoloistConnect: writeEnvFile cache_location="disk" cache_size_mb=1024 ceiling=217 -> ramMb=0 CACHE_SIZE=1024 Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: MPD Permissions set Aug 29 20:27:04 volumio volumio[14078]: info: MPD Permissions set Aug 29 20:27:04 volumio sudo[14261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 20:27:04 volumio sudo[14261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:04 volumio volumio[14078]: info: Volumio called home Aug 29 20:27:04 volumio sudo[14261]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio sudo[14269]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart soloist.service Aug 29 20:27:04 volumio sudo[14269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio launch-soloist.sh[7672]: 2026-08-29 20:27:04.796: shutting down Aug 29 20:27:04 volumio launch-soloist.sh[7672]: 2026-08-29 20:27:04.805: api: websocket server stopped Aug 29 20:27:04 volumio systemd[1]: Stopping soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:04 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:04 volumio launch-soloist.sh[7672]: 2026-08-29 20:27:04.984: stopped Aug 29 20:27:05 volumio systemd[1]: soloist.service: Deactivated successfully. Aug 29 20:27:05 volumio systemd[1]: Stopped soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Aug 29 20:27:05 volumio systemd[1]: soloist.service: Consumed 2min 46.128s CPU time. Aug 29 20:27:05 volumio systemd[1]: Starting soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Aug 29 20:27:05 volumio volumio[14078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 20:27:05 volumio volumio[14078]: info: Upmpdcli Daemon Started Aug 29 20:27:05 volumio systemd[1]: Started soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Aug 29 20:27:05 volumio sudo[14269]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:05 volumio volumio[14078]: info: Starting Shairport Sync Aug 29 20:27:05 volumio volumio[14078]: info: Starting Shairport Sync Aug 29 20:27:05 volumio volumio[14078]: info: Starting Shairport Sync Aug 29 20:27:05 volumio sudo[14309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 20:27:05 volumio sudo[14313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 20:27:05 volumio sudo[14313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:05 volumio sudo[14309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:05 volumio volumio[14078]: info: SoloistConnect: soloist daemon started Aug 29 20:27:05 volumio volumio[14078]: info: SoloistConnect: connecting to ws://127.0.0.1:9878 Aug 29 20:27:05 volumio sudo[14316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 20:27:05 volumio sudo[14316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:05 volumio launch-soloist.sh[14303]: SoloistConnect: plugin=0.8.1 shim=0.2.9 rev=0b2770b3574d7a75263157c53224f6fb19f2badf userspace=armhf device=plug:volumio tlength_cap=500ms external_volume=false trim=0dB diag=off uname=armv7l Aug 29 20:27:05 volumio sudo[14334]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 20:27:05 volumio sudo[14334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:05 volumio sudo[14334]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 20:27:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 20:27:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 20:27:05 volumio systemd[1]: shairport-sync.service: Consumed 5.471s CPU time. Aug 29 20:27:05 volumio sudo[14332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 20:27:05 volumio sudo[14332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:06 volumio sudo[14332]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 20:27:06 volumio volumio[14078]: info: [jellyfin-poller] Polled http://truenas.local:8096/: online Aug 29 20:27:06 volumio sudo[14313]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:06 volumio sudo[14316]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:06 volumio sudo[14309]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:06 volumio volumio[14078]: info: Shairport-Sync Started Aug 29 20:27:06 volumio volumio[14078]: Error adding Membership: Error: addMembership EINVAL Aug 29 20:27:06 volumio volumio[14078]: info: Shairport-Sync Started Aug 29 20:27:06 volumio volumio[14078]: info: Shairport-Sync Started Aug 29 20:27:06 volumio volumio[14078]: verbose: New Socket.io Connection to 192.168.31.71 from 192.168.31.87 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 20:27:06 volumio launch-soloist.sh[14303]: SoloistConnect: interpreter=/data/soloist/sysroot/usr/lib/arm-linux-gnueabihf/ld-linux-armhf.so.3 Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:06 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 20:27:06 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:06.475: soloist 1.3.7.506 build 1788004875 (20260829) (gb24005ef46) (linux/arm) Aug 29 20:27:06 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:06.475: client expires in 89 days Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:06 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::volumioGetQueue Aug 29 20:27:06 volumio volumio[14078]: info: CoreStateMachine::getQueue Aug 29 20:27:06 volumio volumio[14078]: info: CorePlayQueue::getQueue Aug 29 20:27:06 volumio volumio[14078]: info: Listing playlists Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 20:27:06 volumio volumio[14078]: info: Received Get System Info Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:06 volumio volumio[14078]: info: Discovery: Getting this device information Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:06 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:06 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 20:27:06 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 20:27:06 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:06.732: api: websocket server listening on 127.0.0.1:9878 Aug 29 20:27:06 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:06.732: ready, device_name=Volumio device_id=6055b19f-1d63-4cbb-b095-bfff1aaff4db Aug 29 20:27:06 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:06.738: restoring session Aug 29 20:27:06 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:06.740: running Aug 29 20:27:07 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 20:27:07 volumio volumio[14078]: info: Received Get System Info Aug 29 20:27:07 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:27:07 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:07 volumio volumio[14078]: info: Discovery: Getting this device information Aug 29 20:27:07 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:07 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:07 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 20:27:08 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:08.123: logged in as 1c18iundi8sx9ccb3se0komp8 Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 20:27:08 volumio volumio[14078]: info: Received Get System Info Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:08 volumio volumio[14078]: info: Discovery: Getting this device information Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:08 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:08 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 20:27:09 volumio mpd[14259]: 2026-08-29T20:27:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 20:27:09 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 20:27:09 volumio sudo[14224]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:09 volumio sudo[14211]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:09 volumio volumio[14078]: info: Completed starting Core Plugins Aug 29 20:27:09 volumio volumio[14078]: info: ------------------------------------------- Aug 29 20:27:09 volumio volumio[14078]: info: ----- MyVolumio plugins startup ---- Aug 29 20:27:09 volumio volumio[14078]: info: ------------------------------------------- Aug 29 20:27:09 volumio volumio[14078]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 20:27:10 volumio volumio[14078]: error: MPD error: The expression evaluated to a falsy value: Aug 29 20:27:10 volumio volumio[14078]: assert.ok(self.idling) Aug 29 20:27:10 volumio volumio[14078]: error: The expression evaluated to a falsy value: Aug 29 20:27:10 volumio volumio[14078]: assert.ok(self.idling) Aug 29 20:27:10 volumio volumio[14078]: info: MPD running with PID14259 Aug 29 20:27:10 volumio volumio[14078]: ,establishing connection Aug 29 20:27:10 volumio volumio[14078]: error: updateQueue error: null Aug 29 20:27:10 volumio volumio[14078]: error: updateQueue error: null Aug 29 20:27:11 volumio volumio[14078]: info: SoloistConnect: connecting to ws://127.0.0.1:9878 Aug 29 20:27:11 volumio volumio[14078]: info: SoloistConnect: WebSocket connected Aug 29 20:27:11 volumio volumio[14078]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Queue Aug 29 20:27:11 volumio volumio[14078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:11 volumio volumio[14078]: Cannot find translation for source Jellyfin Aug 29 20:27:11 volumio volumio[14078]: info: SoloistConnect: hold yield: is_active=false hold=2000ms Aug 29 20:27:12 volumio volumio[14078]: info: Preload queue cleared Aug 29 20:27:12 volumio volumio[14078]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 20:27:12 volumio volumio[14078]: info: CoreStateMachine::ClearQueue Aug 29 20:27:12 volumio volumio[14078]: info: CoreStateMachine::stop Aug 29 20:27:12 volumio volumio[14078]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:27:12 volumio volumio[14078]: info: CorePlayQueue::clearPlayQueue Aug 29 20:27:12 volumio volumio[14078]: info: CorePlayQueue::saveQueue Aug 29 20:27:12 volumio volumio[14078]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:27:12 volumio volumio[14078]: info: CoreStateMachine::addQueueItems Aug 29 20:27:12 volumio volumio[14078]: info: CorePlayQueue::addQueueItems Aug 29 20:27:12 volumio volumio[14078]: info: Preload queue cleared Aug 29 20:27:12 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:27:12 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 in service jellyfin Aug 29 20:27:12 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:27:12 volumio volumio[14078]: info: [jellyfin-conn] Using previously assigned device Id for attila@truenas: 06b0c9a7-3d6c-4e92-94ed-ba28b690a01c Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-conn] Login successful: attila@truenas Aug 29 20:27:13 volumio volumio[14078]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:27:13 volumio volumio[14078]: info: CorePlayQueue::saveQueue Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::updateTrackBlock Aug 29 20:27:13 volumio volumio[14078]: info: CorePlayQueue::getTrackBlock Aug 29 20:27:13 volumio volumio[14078]: info: CoreCommandRouter::volumioPlay Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::play index 0 Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::addQueueItems Aug 29 20:27:13 volumio volumio[14078]: info: CorePlayQueue::addQueueItems Aug 29 20:27:13 volumio volumio[14078]: info: Preload queue cleared Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=f2e5125b49b6de2edaf7fea8b8906c42 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=f2e5125b49b6de2edaf7fea8b8906c42 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=f2e5125b49b6de2edaf7fea8b8906c42 Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=ebdd469f4fcd74358ee0e87124e87ab9 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=ebdd469f4fcd74358ee0e87124e87ab9 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=ebdd469f4fcd74358ee0e87124e87ab9 Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=4279581cd0fc762a3f8526b3a9280552 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=4279581cd0fc762a3f8526b3a9280552 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=4279581cd0fc762a3f8526b3a9280552 Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01f0aeef4ab1c177aa497c5ab1d6153f Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01f0aeef4ab1c177aa497c5ab1d6153f in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=01f0aeef4ab1c177aa497c5ab1d6153f Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=328df54cc3ccd5120871ed35accaa460 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=328df54cc3ccd5120871ed35accaa460 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=328df54cc3ccd5120871ed35accaa460 Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=8b061ac4330a933baf3c8277f1d0e0fc Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=8b061ac4330a933baf3c8277f1d0e0fc in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=8b061ac4330a933baf3c8277f1d0e0fc Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=c3d5479966e084e23d80ce5596d64438 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=c3d5479966e084e23d80ce5596d64438 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=c3d5479966e084e23d80ce5596d64438 Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=5d7872a484cf1252731721e842071561 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=5d7872a484cf1252731721e842071561 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=5d7872a484cf1252731721e842071561 Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=d1c179d69921d7b26366ab52fe9e8e30 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=d1c179d69921d7b26366ab52fe9e8e30 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=d1c179d69921d7b26366ab52fe9e8e30 Aug 29 20:27:13 volumio volumio[14078]: info: Adding Item to queue: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=75d7a7650b36351b525a5159b0e98b30 Aug 29 20:27:13 volumio volumio[14078]: info: Exploding uri jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=75d7a7650b36351b525a5159b0e98b30 in service jellyfin Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-browse] explodeUri: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/library@parentId=69b9a4a30fa1ebe7a58dbeab78a90c58/songs@albumId=b3ce98f3f95f8850b4cc07a9fc7aeacb/song@songId=75d7a7650b36351b525a5159b0e98b30 Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::stop Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::play index undefined Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:27:13 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:13 volumio volumio[14078]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:27:13 volumio volumio[14078]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:13 volumio volumio[14078]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:27:13 volumio volumio[14078]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:27:13 volumio volumio[14078]: Error: No media streams found for song 01 Vicarious Aug 29 20:27:13 volumio volumio[14078]: at PlayController._PlayController_getStreamUrl (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:241:15) Aug 29 20:27:13 volumio volumio[14078]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:78:234) Aug 29 20:27:13 volumio volumio[14078]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 29 20:27:13 volumio volumio[14078]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:27:15 volumio sudo[14431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 20:26' Aug 29 20:27:15 volumio sudo[14431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:15 volumio sudo[14431]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:15 volumio volumio-remote-updater[706]: [2026-08-29 20:27:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 20:27:15 volumio volumio-remote-updater[706]: [2026-08-29 20:27:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 20:27:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 20:27:15 volumio volumio5-onboarding[1738]: time=2026-08-29T20:27:15.548+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 29 20:27:15 volumio volumio5-onboarding[1738]: time=2026-08-29T20:27:15.553+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 20:27:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 20:27:15 volumio systemd[1]: volumio.service: Consumed 44.216s CPU time. Aug 29 20:27:15 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 29 20:27:15 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Aug 29 20:27:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 29 20:27:15 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 29 20:27:15 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 29 20:27:15 volumio systemd[1]: volumio.service: Consumed 44.216s CPU time. Aug 29 20:27:15 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Aug 29 20:27:15 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Aug 29 20:27:16 volumio volumio5-onboarding[1738]: time=2026-08-29T20:27:16.556+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 20:27:17 volumio volumio5-onboarding[1738]: time=2026-08-29T20:27:17.560+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 29 20:27:19 volumio volumio[14447]: info: ------------------------------------------- Aug 29 20:27:19 volumio volumio[14447]: info: ----- Volumio3 ---- Aug 29 20:27:19 volumio volumio[14447]: info: ------------------------------------------- Aug 29 20:27:19 volumio volumio[14447]: info: ----- System startup ---- Aug 29 20:27:19 volumio volumio[14447]: info: ------------------------------------------- Aug 29 20:27:20 volumio volumio-remote-updater[706]: [2026-08-29 20:27:20] [connect] Successful connection Aug 29 20:27:20 volumio volumio[14447]: info: MYVOLUMIO Environment detected Aug 29 20:27:20 volumio volumio[14447]: info: Plugin folders cleanup Aug 29 20:27:20 volumio volumio[14447]: info: Scanning into folder /volumio/app/plugins/ Aug 29 20:27:20 volumio volumio[14447]: info: Scanning category audio_interface Aug 29 20:27:20 volumio volumio[14447]: info: Scanning category miscellanea Aug 29 20:27:20 volumio volumio[14447]: info: Scanning category music_service Aug 29 20:27:20 volumio volumio[14447]: info: Scanning category plugins.json Aug 29 20:27:20 volumio volumio[14447]: info: Scanning category system_controller Aug 29 20:27:20 volumio volumio[14447]: info: Scanning category user_interface Aug 29 20:27:20 volumio volumio[14447]: info: Scanning into folder /data/plugins/ Aug 29 20:27:20 volumio volumio[14447]: info: Scanning category music_service Aug 29 20:27:20 volumio volumio[14447]: info: Plugin folders cleanup completed Aug 29 20:27:20 volumio volumio[14447]: info: ------------------------------------------- Aug 29 20:27:20 volumio volumio[14447]: info: ----- Core plugins startup ---- Aug 29 20:27:20 volumio volumio[14447]: info: ------------------------------------------- Aug 29 20:27:20 volumio volumio[14447]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 20:27:20 volumio volumio[14447]: info: Adding plugin upnp to MyMusic Plugins Aug 29 20:27:20 volumio volumio[14447]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 20:27:20 volumio volumio[14447]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 20:27:20 volumio volumio[14447]: info: Loading plugins from folder /data/plugins/ Aug 29 20:27:20 volumio volumio[14447]: info: Loading plugin "system"... Aug 29 20:27:20 volumio volumio[14447]: info: Loading plugin "appearance"... Aug 29 20:27:22 volumio volumio[14447]: info: Loading plugin "network"... Aug 29 20:27:22 volumio volumio[14447]: info: Refreshing Cached IP Addresses Aug 29 20:27:22 volumio sudo[14475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 20:27:22 volumio sudo[14475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:22 volumio sudo[14477]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 20:27:22 volumio sudo[14477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:22 volumio sudo[14475]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:22 volumio sudo[14477]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:22 volumio volumio[14447]: info: Loading plugin "services"... Aug 29 20:27:22 volumio volumio[14447]: info: Loading plugin "volumio5onboarding"... Aug 29 20:27:22 volumio sudo[14484]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 20:27:22 volumio sudo[14484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:22 volumio volumio[14447]: info: Loading plugin "alsa_controller"... Aug 29 20:27:23 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:27:23 volumio volumio[14447]: info: Loading plugin "wizard"... Aug 29 20:27:23 volumio volumio[14447]: info: Loading plugin "networkfs"... Aug 29 20:27:23 volumio volumio[14447]: info: Starting Udev Watcher for removable devices Aug 29 20:27:23 volumio volumio[14447]: info: Ignoring mount for partition: boot Aug 29 20:27:23 volumio volumio[14447]: info: Ignoring mount for partition: volumio Aug 29 20:27:23 volumio volumio[14447]: info: Ignoring mount for partition: volumio_data Aug 29 20:27:23 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:27:23 volumio volumio[14447]: info: Loading plugin "volumio_command_line_client"... Aug 29 20:27:23 volumio volumio[14447]: info: Loading plugin "upnp"... Aug 29 20:27:23 volumio volumio[14447]: info: [1788028043226] Starting Upmpd Daemon Aug 29 20:27:23 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:27:23 volumio volumio[14447]: info: Loading plugin "my_music"... Aug 29 20:27:23 volumio volumio[14447]: info: Loading plugin "mpd"... Aug 29 20:27:23 volumio sudo[14484]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:23 volumio volumio[14447]: info: Loading plugin "upnp_browser"... Aug 29 20:27:26 volumio volumio[14447]: info: Starting UPNP Browser Aug 29 20:27:26 volumio volumio[14447]: info: Loading plugin "alarm-clock"... Aug 29 20:27:27 volumio volumio[14447]: info: Loading plugin "airplay_emulation"... Aug 29 20:27:27 volumio volumio[14447]: info: Starting Shairport Sync Aug 29 20:27:27 volumio volumio[14447]: info: Loading plugin "last_100"... Aug 29 20:27:27 volumio volumio[14447]: info: Loading plugin "webradio"... Aug 29 20:27:27 volumio volumio[14447]: info: Loading plugin "i2s_dacs"... Aug 29 20:27:27 volumio volumio[14447]: info: I2S DAC not set, start Auto-detection Aug 29 20:27:27 volumio volumio[14447]: info: Loading plugin "volumiodiscovery"... Aug 29 20:27:27 volumio volumio[14447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 20:27:27 volumio volumio[14447]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:27:27 volumio volumio[14447]: *** WARNING *** For more information see Aug 29 20:27:27 volumio volumio[14447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 20:27:27 volumio volumio[14447]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:27:27 volumio volumio[14447]: *** WARNING *** For more information see Aug 29 20:27:27 volumio node[14447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 20:27:27 volumio node[14447]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:27:27 volumio node[14447]: *** WARNING *** For more information see Aug 29 20:27:27 volumio node[14447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 20:27:27 volumio node[14447]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 20:27:27 volumio node[14447]: *** WARNING *** For more information see Aug 29 20:27:27 volumio volumio[14447]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 20:27:27 volumio volumio[14447]: info: Discovery: Started advertising with name: Volumio Aug 29 20:27:27 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:27:27 volumio volumio[14447]: info: Loading plugin "jellyfin"... Aug 29 20:27:28 volumio volumio5-onboarding[1738]: time=2026-08-29T20:27:28.562+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:57136->127.0.0.1:3000: i/o timeout" Aug 29 20:27:29 volumio volumio[14447]: info: Loading plugin "outputs"... Aug 29 20:27:29 volumio volumio[14447]: info: Loading plugin "albumart"... Aug 29 20:27:29 volumio volumio[14447]: info: Plugin example_plugin is not enabled Aug 29 20:27:29 volumio volumio[14447]: info: Loading plugin "inputs"... Aug 29 20:27:29 volumio volumio[14447]: info: Loading plugin "updater_comm"... Aug 29 20:27:29 volumio volumio[14447]: info: Plugin mpdemulation is not enabled Aug 29 20:27:29 volumio volumio[14447]: info: Loading plugin "rest_api"... Aug 29 20:27:29 volumio volumio[14447]: info: Loading plugin "websocket"... Aug 29 20:27:29 volumio volumio[14447]: info: Starting Socket.io Server version 1.7.4 Aug 29 20:27:29 volumio volumio[14447]: info: Loading plugin "soloist_connect"... Aug 29 20:27:29 volumio volumio[14447]: info: Loading i18n strings for locale en Aug 29 20:27:29 volumio volumio[14447]: Updating browse sources language Aug 29 20:27:29 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::initPlayerControls Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: Express server listening on port 3000 Aug 29 20:27:30 volumio volumio[14447]: [Metrics] WebUI: 11s 868.06ms Aug 29 20:27:30 volumio volumio[14507]: Forking 3 albumart workers Aug 29 20:27:30 volumio volumio[14447]: info: CoreStateMachine::resetVolumioState Aug 29 20:27:30 volumio volumio[14447]: info: CoreStateMachine::getcurrentVolume Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 20:27:30 volumio volumio[14447]: info: CoreStateMachine::pushState Aug 29 20:27:30 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::volumioPushState Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:30 volumio volumio[14447]: info: Volumio Network Manager: Network status updated: 2 Aug 29 20:27:30 volumio volumio[14447]: verbose: New Socket.io Connection to 192.168.31.71 from 192.168.31.87 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 20:27:30 volumio volumio[14447]: info: Reloading queue from file Aug 29 20:27:30 volumio volumio[14447]: info: CoreStateMachine::setRepeat null single undefined Aug 29 20:27:30 volumio volumio[14447]: info: CoreStateMachine::pushState Aug 29 20:27:30 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::volumioPushState Aug 29 20:27:30 volumio volumio[14447]: info: CoreStateMachine::setRandom null Aug 29 20:27:30 volumio volumio[14447]: info: CoreStateMachine::pushState Aug 29 20:27:30 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:30 volumio volumio[14447]: info: CoreCommandRouter::volumioPushState Aug 29 20:27:30 volumio volumio[14447]: info: Setting Device type: Raspberry PI Aug 29 20:27:31 volumio volumio[14447]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 29 20:27:31 volumio volumio[14447]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 29 20:27:31 volumio volumio[14447]: info: Received Get System Info Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:31 volumio volumio[14447]: info: Discovery: Getting this device information Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:31 volumio volumio[14447]: verbose: New Socket.io Connection to 192.168.31.71 from 192.168.31.87 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 20:27:31 volumio volumio[14447]: info: Completed loading Core Plugins Aug 29 20:27:31 volumio volumio[14447]: info: Preparing to generate the ALSA configuration file Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 20:27:31 volumio volumio[14447]: info: Discovery: adding bea9bc0b-e08a-47f5-b4e5-a0285ad6c485 Aug 29 20:27:31 volumio volumio[14447]: info: Discovery: Found device Volumio Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:31 volumio volumio[14447]: info: Discovery: this is already registered, bea9bc0b-e08a-47f5-b4e5-a0285ad6c485 Aug 29 20:27:31 volumio volumio[14447]: info: Discovery: Found device Volumio Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 20:27:31 volumio volumio[14447]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 20:27:31 volumio volumio[14447]: info: Received Get System Version Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:27:31 volumio volumio[14447]: info: Received Get System Info Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:31 volumio volumio[14447]: info: Discovery: Getting this device information Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetQueue Aug 29 20:27:31 volumio volumio[14447]: info: CoreStateMachine::getQueue Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getQueue Aug 29 20:27:31 volumio volumio[14447]: info: Listing playlists Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 20:27:31 volumio volumio[14447]: info: Received Get System Info Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:31 volumio volumio[14447]: info: Discovery: Getting this device information Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:31 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:31 volumio volumio[14447]: info: Asound.conf file unchanged, so no further update is needed Aug 29 20:27:31 volumio volumio[14447]: info: Output device has changed, restarting MPD Aug 29 20:27:31 volumio volumio[14447]: info: Output device has changed, restarting Shairport Sync Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:31 volumio sudo[14560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 20:27:31 volumio sudo[14560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:31 volumio volumio[14447]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 20:27:31 volumio sudo[14560]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:31 volumio volumio[14447]: info: ___________ START PLUGINS ___________ Aug 29 20:27:31 volumio sudo[14562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 20:27:31 volumio sudo[14562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:31 volumio volumio[14447]: info: ControllerMpd::onStart: Initializing MPD Aug 29 20:27:31 volumio volumio[14447]: info: Creating MPD Configuration file Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:31 volumio volumio[14447]: info: [1788028051817] CoreMusicLibrary::Adding element Media Servers Aug 29 20:27:31 volumio sudo[14570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 29 20:27:31 volumio sudo[14570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:31 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 20:27:31 volumio sudo[14570]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:32 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 20:27:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 20:27:32 volumio systemd[1]: mpd.service: Consumed 6.970s CPU time. Aug 29 20:27:32 volumio sudo[14572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 20:27:32 volumio sudo[14572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 20:27:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 20:27:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 20:27:32 volumio volumio[14447]: info: UPNP Browser: Client initialized successfully Aug 29 20:27:32 volumio sudo[14572]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:32 volumio sudo[14574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 20:27:32 volumio sudo[14574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 20:27:32 volumio volumio5-onboarding[1738]: time=2026-08-29T20:27:32.077+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 20:27:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:32 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 20:27:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 20:27:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 20:27:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 20:27:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 20:27:32 volumio volumio[14447]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 20:27:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 20:27:32 volumio volumio[14447]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:32 volumio volumio[14447]: info: [1788028052384] CoreMusicLibrary::Adding element Last_100 Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:32 volumio volumio[14447]: info: [1788028052399] CoreMusicLibrary::Adding element Webradio Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:27:32 volumio volumio[14447]: info: Initializing BBC Radios Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 20:27:32 volumio volumio[14447]: info: Discovery: Getting this device information Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:32 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 20:27:32 volumio volumio[14447]: info: [1788028052785] CoreMusicLibrary::Adding element Jellyfin Aug 29 20:27:32 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 20:27:32 volumio volumio[14447]: Cannot find translation for source Jellyfin Aug 29 20:27:32 volumio volumio[14447]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.31.71","id":"bea9bc0b-e08a-47f5-b4e5-a0285ad6c485","name":"Volumio","serviceName":"Volumio","state":{"albumart":"http://192.168.31.71/albumart?sourceicon=music_service%2Fjellyfin%2Fdist%2Fassets%2Fimages%2Fsong.png","artist":"","mute":false,"status":"stop","track":"01 Vicarious","volume":100},"type":"device"} Aug 29 20:27:32 volumio sudo[14589]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 20:27:32 volumio sudo[14589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 20:27:32 volumio volumio[14447]: info: SoloistConnect: plugin=0.8.1 shim=0.2.9 Aug 29 20:27:32 volumio sudo[14589]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:32 volumio volumio[14447]: info: Volumio Calling Home Aug 29 20:27:33 volumio volumio-remote-updater[706]: [2026-08-29 20:27:33] [connect] Successful connection Aug 29 20:27:33 volumio volumio[14520]: Starting albumart workers Aug 29 20:27:33 volumio volumio[14523]: Starting albumart workers Aug 29 20:27:34 volumio volumio[14521]: Starting albumart workers Aug 29 20:27:34 volumio sudo[14625]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 20:27:34 volumio sudo[14625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:34 volumio sudo[14627]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 20:27:34 volumio sudo[14625]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:34 volumio sudo[14627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:34 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , soloistMeteringWanted Aug 29 20:27:34 volumio volumio[14447]: info: SoloistConnect: writeEnvFile cache_location="disk" cache_size_mb=1024 ceiling=217 -> ramMb=0 CACHE_SIZE=1024 Aug 29 20:27:34 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:34 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:34 volumio sudo[14627]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:34 volumio volumio[14447]: info: MPD Permissions set Aug 29 20:27:34 volumio volumio[14447]: info: MPD Permissions set Aug 29 20:27:34 volumio volumio-remote-updater[706]: [2026-08-29 20:27:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788028053 101 Aug 29 20:27:34 volumio volumio[14447]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Aug 29 20:27:34 volumio volumio[14447]: info: Volumio called home Aug 29 20:27:34 volumio sudo[14631]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart soloist.service Aug 29 20:27:34 volumio sudo[14631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:35 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:35.041: shutting down Aug 29 20:27:35 volumio launch-soloist.sh[14303]: 2026-08-29 20:27:35.045: api: websocket server stopped Aug 29 20:27:35 volumio systemd[1]: Stopping soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Aug 29 20:27:35 volumio volumio[14447]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 20:27:35 volumio volumio[14447]: info: [jellyfin-poller] Polled http://truenas.local:8096/: online Aug 29 20:27:35 volumio volumio[14447]: info: Starting Shairport Sync Aug 29 20:27:35 volumio volumio[14447]: info: Starting Shairport Sync Aug 29 20:27:35 volumio volumio[14447]: info: Starting Shairport Sync Aug 29 20:27:35 volumio systemd[1]: soloist.service: Deactivated successfully. Aug 29 20:27:35 volumio systemd[1]: Stopped soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Aug 29 20:27:35 volumio systemd[1]: soloist.service: Consumed 7.462s CPU time. Aug 29 20:27:35 volumio sudo[14645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 20:27:35 volumio sudo[14645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:35 volumio sudo[14646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 20:27:35 volumio sudo[14646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:35 volumio systemd[1]: Starting soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Aug 29 20:27:35 volumio sudo[14648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 20:27:35 volumio sudo[14648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:35 volumio volumio[14447]: info: CoreCommandRouter::volumioGetState Aug 29 20:27:35 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:35 volumio sudo[14651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 20:27:35 volumio sudo[14651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 20:27:35 volumio systemd[1]: Started soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Aug 29 20:27:35 volumio sudo[14631]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:36 volumio volumio[14447]: info: SoloistConnect: soloist daemon started Aug 29 20:27:36 volumio volumio[14447]: info: SoloistConnect: connecting to ws://127.0.0.1:9878 Aug 29 20:27:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 20:27:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 20:27:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 20:27:36 volumio systemd[1]: shairport-sync.service: Consumed 2.038s CPU time. Aug 29 20:27:36 volumio launch-soloist.sh[14665]: SoloistConnect: plugin=0.8.1 shim=0.2.9 rev=0b2770b3574d7a75263157c53224f6fb19f2badf userspace=armhf device=plug:volumio tlength_cap=500ms external_volume=false trim=0dB diag=off uname=armv7l Aug 29 20:27:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 20:27:36 volumio sudo[14646]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:36 volumio sudo[14645]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:36 volumio volumio[14447]: info: Shairport-Sync Started Aug 29 20:27:36 volumio launch-soloist.sh[14665]: SoloistConnect: interpreter=/data/soloist/sysroot/usr/lib/arm-linux-gnueabihf/ld-linux-armhf.so.3 Aug 29 20:27:36 volumio volumio[14447]: Error adding Membership: Error: addMembership EINVAL Aug 29 20:27:36 volumio volumio[14447]: info: Shairport-Sync Started Aug 29 20:27:36 volumio sudo[14648]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:36 volumio volumio[14447]: info: Shairport-Sync Started Aug 29 20:27:36 volumio sudo[14651]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:36 volumio volumio[14447]: info: Upmpdcli Daemon Started Aug 29 20:27:36 volumio launch-soloist.sh[14665]: 2026-08-29 20:27:36.573: soloist 1.3.7.506 build 1788004875 (20260829) (gb24005ef46) (linux/arm) Aug 29 20:27:36 volumio launch-soloist.sh[14665]: 2026-08-29 20:27:36.573: client expires in 89 days Aug 29 20:27:36 volumio launch-soloist.sh[14665]: 2026-08-29 20:27:36.770: api: websocket server listening on 127.0.0.1:9878 Aug 29 20:27:36 volumio launch-soloist.sh[14665]: 2026-08-29 20:27:36.770: ready, device_name=Volumio device_id=6055b19f-1d63-4cbb-b095-bfff1aaff4db Aug 29 20:27:36 volumio launch-soloist.sh[14665]: 2026-08-29 20:27:36.774: restoring session Aug 29 20:27:36 volumio launch-soloist.sh[14665]: 2026-08-29 20:27:36.774: running Aug 29 20:27:37 volumio volumio[14447]: info: CoreCommandRouter::volumioPlay Aug 29 20:27:37 volumio volumio[14447]: info: CoreStateMachine::play index undefined Aug 29 20:27:37 volumio volumio[14447]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:27:37 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:37 volumio volumio[14447]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:27:37 volumio volumio[14447]: info: CorePlayQueue::getTrack 0 Aug 29 20:27:37 volumio volumio[14447]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/attila@60b0c96ef59a48faada7380aa146ea84/song@songId=01e5da6bc4d5844a68a632eb91f4aea0 Aug 29 20:27:38 volumio volumio[14447]: info: [jellyfin-conn] Using previously assigned device Id for attila@truenas: 06b0c9a7-3d6c-4e92-94ed-ba28b690a01c Aug 29 20:27:38 volumio launch-soloist.sh[14665]: 2026-08-29 20:27:38.184: logged in as 1c18iundi8sx9ccb3se0komp8 Aug 29 20:27:38 volumio volumio[14447]: info: [jellyfin-conn] Login successful: attila@truenas Aug 29 20:27:38 volumio volumio[14447]: info: [jellyfin-play] Stream URL for Vicarious: http://truenas.local:8096/Audio/01e5da6bc4d5844a68a632eb91f4aea0/stream.flac?static=true&mediaSourceId=01e5da6bc4d5844a68a632eb91f4aea0&tag=bc93252e492b9f25a7cdcad4dce6b25e Aug 29 20:27:38 volumio volumio[14447]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:27:38 volumio volumio[14447]: TypeError: Cannot read properties of undefined (reading 'on') Aug 29 20:27:38 volumio volumio[14447]: at PlayController._PlayController_addListeners (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:184:79) Aug 29 20:27:38 volumio volumio[14447]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:80:100) Aug 29 20:27:38 volumio volumio[14447]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 29 20:27:38 volumio volumio[14447]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:27:39 volumio mpd[14604]: 2026-08-29T20:27:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 20:27:39 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 20:27:39 volumio sudo[14562]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:39 volumio sudo[14574]: pam_unix(sudo:session): session closed for user root Aug 29 20:27:40 volumio sudo[14770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 20:26' Aug 29 20:27:40 volumio sudo[14770]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"