Mar 29 23:23:01 volumio go-librespot[20827]: time="2026-03-29T23:23:01+05:30" level=trace msg="received accesspoint ping"
Mar 29 23:23:02 volumio go-librespot[20827]: time="2026-03-29T23:23:02+05:30" level=trace msg="received accesspoint pong ack"
Mar 29 23:23:02 volumio go-librespot[20827]: time="2026-03-29T23:23:02+05:30" level=trace msg="sent dealer ping"
Mar 29 23:23:02 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 29 23:23:02 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Mar 29 23:23:02 volumio volumio[20612]: info: CURURI: albums://
Mar 29 23:23:02 volumio volumio[20612]: info: listAlbums - loading Albums from cache
Mar 29 23:23:02 volumio go-librespot[20827]: time="2026-03-29T23:23:02+05:30" level=trace msg="received dealer pong"
Mar 29 23:23:02 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:09 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri
Mar 29 23:23:09 volumio volumio[20612]: info: [soundcloud] browseUri: soundcloud
Mar 29 23:23:11 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 29 23:23:11 volumio volumio[20612]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Mar 29 23:23:11 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Mar 29 23:23:11 volumio volumio[20612]: info: Received Get System Version
Mar 29 23:23:11 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 29 23:23:11 volumio volumio[20612]: info: Received Get System Info
Mar 29 23:23:11 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 29 23:23:11 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 29 23:23:11 volumio volumio[20612]: info: Discovery: Getting this device information
Mar 29 23:23:11 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:11 volumio volumio[20612]: info: CorePlayQueue::getTrack 5
Mar 29 23:23:11 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 29 23:23:13 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:13 volumio volumio[20612]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2277071279
Mar 29 23:23:13 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:13 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:13 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:13 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:13 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:13 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:13 volumio bluealsa[1027]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_56_8C_47_39_A9_88, ...)
Mar 29 23:23:15 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:15.043+05:30 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.38:55676 @ 0x295e300" latency=410.926022ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE
Mar 29 23:23:15 volumio sudo[22825]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 29 23:23:15 volumio sudo[22825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 29 23:23:15 volumio sudo[22825]: pam_unix(sudo:session): session closed for user root
Mar 29 23:23:15 volumio sudo[22827]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 29 23:23:15 volumio sudo[22827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 29 23:23:15 volumio sudo[22827]: pam_unix(sudo:session): session closed for user root
Mar 29 23:23:15 volumio volumio[20612]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.38 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Mar 29 23:23:16 volumio sudo[22845]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 29 23:23:16 volumio sudo[22845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 29 23:23:16 volumio sudo[22845]: pam_unix(sudo:session): session closed for user root
Mar 29 23:23:16 volumio sudo[22847]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 29 23:23:16 volumio sudo[22847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 29 23:23:16 volumio sudo[22847]: pam_unix(sudo:session): session closed for user root
Mar 29 23:23:16 volumio volumio[20612]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.38 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:16 volumio volumio[20612]: info: CorePlayQueue::getTrack 5
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::volumioGetQueue
Mar 29 23:23:16 volumio volumio[20612]: info: CoreStateMachine::getQueue
Mar 29 23:23:16 volumio volumio[20612]: info: CorePlayQueue::getQueue
Mar 29 23:23:16 volumio volumio[20612]: info: Listing playlists
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 29 23:23:16 volumio volumio[20612]: info: Received Get System Info
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 29 23:23:16 volumio volumio[20612]: info: Discovery: Getting this device information
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:16 volumio volumio[20612]: info: CorePlayQueue::getTrack 5
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:16 volumio volumio[20612]: info: CorePlayQueue::getTrack 5
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 29 23:23:16 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 29 23:23:17 volumio bluealsa[1027]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_4F_5C_CE_08_13_D1, ...)
Mar 29 23:23:17 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Mar 29 23:23:18 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 29 23:23:18 volumio volumio[20612]: info: Received Get System Info
Mar 29 23:23:18 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 29 23:23:18 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 29 23:23:18 volumio volumio[20612]: info: Discovery: Getting this device information
Mar 29 23:23:18 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:18 volumio volumio[20612]: info: CorePlayQueue::getTrack 5
Mar 29 23:23:18 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 29 23:23:19 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 29 23:23:19 volumio volumio[20612]: info: Received Get System Info
Mar 29 23:23:19 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 29 23:23:19 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 29 23:23:19 volumio volumio[20612]: info: Discovery: Getting this device information
Mar 29 23:23:19 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:19 volumio volumio[20612]: info: CorePlayQueue::getTrack 5
Mar 29 23:23:19 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 29 23:23:23 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:23 volumio volumio[20612]: info: CoreCommandRouter::volumioReplaceandPlayItems
Mar 29 23:23:23 volumio volumio[20612]: info: CoreStateMachine::ClearQueue
Mar 29 23:23:23 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:23:23 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:23:23 volumio volumio[20612]: info: CorePlayQueue::clearPlayQueue
Mar 29 23:23:23 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:23:23 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:23:23 volumio volumio[20612]: info: CoreStateMachine::addQueueItems
Mar 29 23:23:23 volumio volumio[20612]: info: CorePlayQueue::addQueueItems
Mar 29 23:23:23 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:23:23 volumio volumio[20612]: info: Adding Item to queue: soundcloud/playlists@playlistId=734469195
Mar 29 23:23:23 volumio volumio[20612]: info: Exploding uri soundcloud/playlists@playlistId=734469195 in service soundcloud
Mar 29 23:23:23 volumio volumio[20612]: info: [soundcloud] explodeUri: soundcloud/playlists@playlistId=734469195
Mar 29 23:23:23 volumio bluealsa[1027]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_64_3D_11_8A_A7_F7, ...)
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2291262926@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2287328096@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2285702579@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2277050408@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2290602986@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2289500051@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2290661804@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2165506116@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2286685799@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2285462375@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2273596850@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2282520860@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2287464641@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2257004129@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2274227210@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2274828434@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2279005736@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2283981554@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2259401771@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2154156810@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2266767728@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2265590657@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2284221164@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2261429057@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2273703446@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2199578283@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2286370706@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2267785181@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2256823808@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2252390318@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2269350515@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2268530618@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2266355036@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2252368154@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2280809948@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2277020864@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2190339087@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2265254720@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2269679051@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2261397920@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2265683075@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2261012534@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2264392352@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2109535716@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2230919879@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2240088560@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:23:24 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::updateTrackBlock
Mar 29 23:23:24 volumio volumio[20612]: info: CorePlayQueue::getTrackBlock
Mar 29 23:23:24 volumio volumio[20612]: info: CoreCommandRouter::volumioPlay
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::play index 0
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::play index undefined
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:23:24 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::startPlaybackTimer
Mar 29 23:23:24 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] Going to start vlc for playback
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] cvlc process spawned
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [01646390] dbus interface error: Failed to connect to the D-Bus session daemon: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Mar 29 23:23:24 volumio volumio[20612]: [01646390] main interface error: no suitable interface module
Mar 29 23:23:24 volumio volumio[20612]: [015db688] main libvlc error: interface "dbus,none" initialization failed
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [01646390] [http] lua interface: Lua HTTP interface
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [01687c20] main interface error: no suitable interface module
Mar 29 23:23:24 volumio volumio[20612]: [015db688] main libvlc error: interface "globalhotkeys,none" initialization failed
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [01687c20] dummy interface: using the dummy interface module...
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [01631c38] main playlist: playlist is empty
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Started and responding on port 10000
Mar 29 23:23:24 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:24 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Stopping playback by current service...
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:23:24 volumio volumio[20612]: info: CoreCommandRouter::volumioStop
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Setting ourselves as the current service...
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:23:24 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-vQZ2Wdwz8qDtQe9j-azUPbQ-t500x500.jpg","uri":"soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D","seek":0,"duration":0,"service":"soundcloud","artist":"crayon","album":"SoundCloud Track","name":"legs","title":"legs","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:23:24 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:23:24 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:23:24 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:23:24 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:23:24 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:24.980+05:30 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x295f1d0" state=STATUS_PAUSED positionMs=0 volume=30
Mar 29 23:23:24 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:25 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D","title":"legs","artist":"crayon","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-vQZ2Wdwz8qDtQe9j-azUPbQ-t500x500.jpg","trackType":"aac","duration":-1,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:23:25 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:23:25 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:25.030+05:30 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x295f1d0" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:23:25 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:25 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D","title":"legs","artist":"crayon","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-vQZ2Wdwz8qDtQe9j-azUPbQ-t500x500.jpg","trackType":"aac","duration":99,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:23:25 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:23:25 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:23:25 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:25.665+05:30 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x295f1d0" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:23:25 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:23:25 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] adaptive demux: Changing stream format Unknown -> MP4
Mar 29 23:23:25 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [01686a98] mp4 demux: Fragment sequence discontinuity detected 1 != 0
Mar 29 23:23:26 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 1 error: Cancellation (0x8)
Mar 29 23:23:26 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 29 23:23:26 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 3 error: Cancellation (0x8)
Mar 29 23:23:26 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 5 error: Cancellation (0x8)
Mar 29 23:23:27 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 7 error: Cancellation (0x8)
Mar 29 23:23:28 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:28.289+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=1 index=0 tries=11
Mar 29 23:23:28 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:28.290+05:30 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%02 @ 0x295f1d0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone"
Mar 29 23:23:28 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:28.290+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:49818 @ 0x276c120" state=STATUS_PAUSED positionMs=0 volume=30
Mar 29 23:23:28 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:28.308+05:30 level=ERROR msg="failed to send event" component=server dst="192.168.1.38:49818 @ 0x276c120" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no WebSocket connection found for address: 192.168.1.38:49818"
Mar 29 23:23:28 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:28.308+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676 @ 0x295e300" state=STATUS_PAUSED positionMs=0 volume=30
Mar 29 23:23:28 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:28.308+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:23:31 volumio bluealsa[1027]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_7C_70_4B_02_F0_8D, ...)
Mar 29 23:23:31 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:31.598+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=1 index=0 tries=11
Mar 29 23:23:31 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:31.599+05:30 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%02 @ 0x295f1d0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone"
Mar 29 23:23:31 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:31.599+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:49818 @ 0x276c120" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:23:31 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:31.599+05:30 level=ERROR msg="failed to send event" component=server dst="192.168.1.38:49818 @ 0x276c120" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no WebSocket connection found for address: 192.168.1.38:49818"
Mar 29 23:23:31 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:31.599+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:23:31 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:31.599+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:23:32 volumio go-librespot[20827]: time="2026-03-29T23:23:32+05:30" level=trace msg="sent dealer ping"
Mar 29 23:23:32 volumio go-librespot[20827]: time="2026-03-29T23:23:32+05:30" level=trace msg="received dealer pong"
Mar 29 23:23:33 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:33.439+05:30 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4
Mar 29 23:23:33 volumio dbus-daemon[704]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.15" (uid=0 pid=1494 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=930 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b")
Mar 29 23:23:34 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 29 23:23:34 volumio volumio[20612]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Mar 29 23:23:34 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Mar 29 23:23:34 volumio volumio[20612]: info: Received Get System Version
Mar 29 23:23:34 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 29 23:23:34 volumio volumio[20612]: info: Received Get System Info
Mar 29 23:23:34 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 29 23:23:34 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 29 23:23:34 volumio volumio[20612]: info: Discovery: Getting this device information
Mar 29 23:23:34 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:23:34 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 29 23:23:34 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:34.912+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=1 index=0 tries=11
Mar 29 23:23:34 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:34.912+05:30 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%02 @ 0x295f1d0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone"
Mar 29 23:23:34 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:34.912+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:49818 @ 0x276c120" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:23:34 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:34.912+05:30 level=ERROR msg="failed to send event" component=server dst="192.168.1.38:49818 @ 0x276c120" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no WebSocket connection found for address: 192.168.1.38:49818"
Mar 29 23:23:34 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:34.912+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:23:34 volumio volumio5-onboarding[1494]: time=2026-03-29T23:23:34.913+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:23:35 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 9 error: Cancellation (0x8)
Mar 29 23:23:36 volumio kernel: usb 1-1.3: USB disconnect, device number 3
Mar 29 23:23:36 volumio volumio[20612]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-uuid/6DF7-0D48 /dev/disk/by-id/usb-SanDisk_Cruzer_Blade_4C530199920726109360-0:0-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6DF7-0D48","ID_FS_UUID_ENC":"6DF7-0D48","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Cruzer_Blade","ID_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5567","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"31266784","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.27","ID_SERIAL":"SanDisk_Cruzer_Blade_4C530199920726109360-0:0","ID_SERIAL_SHORT":"4C530199920726109360","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"Cruzer_Blade","ID_USB_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5567","ID_USB_REVISION":"1.27","ID_USB_SERIAL":"SanDisk_Cruzer_Blade_4C530199920726109360-0:0","ID_USB_SERIAL_SHORT":"4C530199920726109360","ID_USB_TYPE":"disk","ID_USB_VENDOR":"SanDisk","ID_USB_VENDOR_ENC":"SanDisk\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"SanDisk","ID_VENDOR_ENC":"SanDisk\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2755","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"15654390245"}
Mar 29 23:23:36 volumio sudo[22918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1
Mar 29 23:23:36 volumio sudo[22918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 29 23:23:36 volumio systemd[1]: media-6DF7\x2d0D48.mount: Deactivated successfully.
Mar 29 23:23:36 volumio sudo[22918]: pam_unix(sudo:session): session closed for user root
Mar 29 23:23:40 volumio kernel: usb 1-1.1: new high-speed USB device number 4 using xhci_hcd
Mar 29 23:23:40 volumio kernel: usb 1-1.1: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.27
Mar 29 23:23:40 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar 29 23:23:40 volumio kernel: usb 1-1.1: Product: Cruzer Blade
Mar 29 23:23:40 volumio kernel: usb 1-1.1: Manufacturer: SanDisk
Mar 29 23:23:40 volumio kernel: usb 1-1.1: SerialNumber: 4C530199920726109360
Mar 29 23:23:40 volumio kernel: usb-storage 1-1.1:1.0: USB Mass Storage device detected
Mar 29 23:23:40 volumio kernel: scsi host0: usb-storage 1-1.1:1.0
Mar 29 23:23:40 volumio mtp-probe[22925]: checking bus 1, device 4: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1"
Mar 29 23:23:40 volumio mtp-probe[22925]: bus: 1, device: 4 was not an MTP device
Mar 29 23:23:40 volumio fsct-plugin[20612]: Failed to initialize device 0781:5567: BOS descriptor not available, unsupported usb version 2.00
Mar 29 23:23:40 volumio mtp-probe[22926]: checking bus 1, device 4: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1"
Mar 29 23:23:40 volumio mtp-probe[22926]: bus: 1, device: 4 was not an MTP device
Mar 29 23:23:40 volumio volumio[20612]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/6DF7-0D48
Mar 29 23:23:41 volumio kernel: scsi 0:0:0:0: Direct-Access SanDisk Cruzer Blade 1.27 PQ: 0 ANSI: 6
Mar 29 23:23:41 volumio kernel: scsi 0:0:0:0: Attached scsi generic sg0 type 0
Mar 29 23:23:41 volumio kernel: sd 0:0:0:0: [sda] 31266816 512-byte logical blocks: (16.0 GB/14.9 GiB)
Mar 29 23:23:41 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 29 23:23:41 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Mar 29 23:23:41 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
Mar 29 23:23:41 volumio volumio[20612]: info: Scanning removed location : "USB/6DF7-0D48"
Mar 29 23:23:41 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:23:41 volumio kernel: sda: sda1
Mar 29 23:23:41 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk
Mar 29 23:23:41 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:23:41 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:23:41 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:23:42 volumio volumio[20612]: info: Mounting Device 6DF7-0D48
Mar 29 23:23:42 volumio sudo[22942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/6DF7-0D48 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Mar 29 23:23:42 volumio sudo[22942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 29 23:23:42 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Mar 29 23:23:42 volumio sudo[22942]: pam_unix(sudo:session): session closed for user root
Mar 29 23:23:42 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Mar 29 23:23:42 volumio volumio[20612]: info: Scanning new location : "USB/6DF7-0D48"
Mar 29 23:23:42 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:23:42 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:23:44 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 11 error: Cancellation (0x8)
Mar 29 23:23:48 volumio bluealsa[1027]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_56_8C_47_39_A9_88, ...)
Mar 29 23:23:54 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 13 error: Cancellation (0x8)
Mar 29 23:24:02 volumio go-librespot[20827]: time="2026-03-29T23:24:02+05:30" level=trace msg="sent dealer ping"
Mar 29 23:24:02 volumio go-librespot[20827]: time="2026-03-29T23:24:02+05:30" level=trace msg="received dealer pong"
Mar 29 23:24:03 volumio volumio[20612]: info: MPD Database updated - AlbumList cache refreshed
Mar 29 23:24:03 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:24:03 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:24:03 volumio volumio[20612]: info: Ignoring MPD Status Update
Mar 29 23:24:03 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioReplaceandPlayItems
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::ClearQueue
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::serviceStop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::serviceStop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::stPlaybackTimer
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::updateTrackBlock
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::getTrackBlock
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::clearPlayQueue
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::addQueueItems
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::addQueueItems
Mar 29 23:24:03 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:24:03 volumio volumio[20612]: info: Adding Item to queue: soundcloud/playlists@playlistId=734469195
Mar 29 23:24:03 volumio volumio[20612]: info: Using cached record of: soundcloud/playlists@playlistId=734469195
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::updateTrackBlock
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::getTrackBlock
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPlay
Mar 29 23:24:03 volumio volumio[20612]: verbose: UNSET VOLATILE: Service: soundcloud
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Volatile state unset, stopping playback (if any)...
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":36000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:03 volumio volumio[20612]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":36000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:03 volumio volumio[20612]: verbose: CURRENT POSITION 0
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::syncState stateService stop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::syncState currentStatus stop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:03 volumio volumio[20612]: info: No code
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::play index 0
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.272+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=36000 volume=30
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.273+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.273+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=36000 volume=30
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.274+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.275+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=36000 volume=30
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.275+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::play index undefined
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::startPlaybackTimer
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D
Mar 29 23:24:03 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:03 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:03 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 15 error: Cancellation (0x8)
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b50f88a8] http demux error: local stream 1 error: Cancellation (0x8)
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Player status "stopped" - unsetting ourselves as current service...
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:03 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Stopping playback by current service...
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioStop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Setting ourselves as the current service...
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-vQZ2Wdwz8qDtQe9j-azUPbQ-t500x500.jpg","uri":"soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D","seek":0,"duration":0,"service":"soundcloud","artist":"crayon","album":"SoundCloud Track","name":"legs","title":"legs","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.928+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PAUSED positionMs=0 volume=30
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.929+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:03 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:03 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D","title":"legs","artist":"crayon","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-vQZ2Wdwz8qDtQe9j-azUPbQ-t500x500.jpg","trackType":"aac","duration":-1,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:03 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.993+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:24:03 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:03.994+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:03 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:04 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:04 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D","title":"legs","artist":"crayon","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-vQZ2Wdwz8qDtQe9j-azUPbQ-t500x500.jpg","trackType":"aac","duration":99,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:04 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:04 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:04 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:04 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:04 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:04.787+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:24:04 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:04.787+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:04 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:04 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52161b8] adaptive demux: Changing stream format Unknown -> MP4
Mar 29 23:24:05 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b1ead0a8] mp4 demux: Fragment sequence discontinuity detected 1 != 0
Mar 29 23:24:05 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52161b8] http demux error: local stream 1 error: Cancellation (0x8)
Mar 29 23:24:05 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52161b8] http demux error: local stream 3 error: Cancellation (0x8)
Mar 29 23:24:05 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52161b8] http demux error: local stream 5 error: Cancellation (0x8)
Mar 29 23:24:06 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52161b8] http demux error: local stream 7 error: Cancellation (0x8)
Mar 29 23:24:08 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::volumioReplaceandPlayItems
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::ClearQueue
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::serviceStop
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::serviceStop
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::stPlaybackTimer
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::updateTrackBlock
Mar 29 23:24:08 volumio volumio[20612]: info: CorePlayQueue::getTrackBlock
Mar 29 23:24:08 volumio volumio[20612]: info: CorePlayQueue::clearPlayQueue
Mar 29 23:24:08 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::addQueueItems
Mar 29 23:24:08 volumio volumio[20612]: info: CorePlayQueue::addQueueItems
Mar 29 23:24:08 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:24:08 volumio volumio[20612]: info: Adding Item to queue: soundcloud/playlists@playlistId=1690636509
Mar 29 23:24:08 volumio volumio[20612]: info: Exploding uri soundcloud/playlists@playlistId=1690636509 in service soundcloud
Mar 29 23:24:08 volumio volumio[20612]: info: [soundcloud] explodeUri: soundcloud/playlists@playlistId=1690636509
Mar 29 23:24:08 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52161b8] http demux error: local stream 9 error: Cancellation (0x8)
Mar 29 23:24:08 volumio volumio[20612]: [b52161b8] http demux error: local stream 1 error: Cancellation (0x8)
Mar 29 23:24:08 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Player status "stopped" - unsetting ourselves as current service...
Mar 29 23:24:08 volumio volumio[20612]: verbose: UNSET VOLATILE: Service: soundcloud
Mar 29 23:24:08 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Volatile state unset, stopping playback (if any)...
Mar 29 23:24:08 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:08 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:08 volumio volumio[20612]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:08 volumio volumio[20612]: verbose: CURRENT POSITION 0
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::syncState stateService stop
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::syncState currentStatus stop
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:08 volumio volumio[20612]: info: No code
Mar 29 23:24:08 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:08 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:08 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:08.506+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:24:08 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:08.506+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:08 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:08.508+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:24:08 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:08.508+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:08 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:08.509+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:24:08 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:08.510+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=2222955869@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A734469195%7D" title=legs
Mar 29 23:24:08 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:08 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:08 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1610040885@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1606049703@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1601306430@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1173189193@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1595801349@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1582122087@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1570720588@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1563398065@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1557578650@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1553767969@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1556374555@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1557030139@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1546543690@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1507674034@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1541577781@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1529296198@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1463859232@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1519988515@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1502230417@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1582565771@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=658622000@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1536338857@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=661036613@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1512289852@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1610126151@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1541503792@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1605080862@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1594147755@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1005661834@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=254681311@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1502215423@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1421763997@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1539320896@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1585104863@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1610229699@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1541194747@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1595371902@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1588632243@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1582254779@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1619018256@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:24:09 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::updateTrackBlock
Mar 29 23:24:09 volumio volumio[20612]: info: CorePlayQueue::getTrackBlock
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::volumioPlay
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::play index 0
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::play index undefined
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:09 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::startPlaybackTimer
Mar 29 23:24:09 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:09 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Stopping playback by current service...
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::volumioStop
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Setting ourselves as the current service...
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-Cz76cdFb8MNWUsLb-zwwz0g-t500x500.jpg","uri":"soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D","seek":0,"duration":0,"service":"soundcloud","artist":"heygwuapo","album":"SoundCloud Track","name":"free bands","title":"free bands","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:09 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:09.534+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PAUSED positionMs=0 volume=30
Mar 29 23:24:09 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:09.535+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D" title="free bands"
Mar 29 23:24:09 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:09 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D","title":"free bands","artist":"heygwuapo","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-Cz76cdFb8MNWUsLb-zwwz0g-t500x500.jpg","trackType":"aac","duration":-1,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:09 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:09 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:09 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:09.575+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:24:09 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:09.575+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D" title="free bands"
Mar 29 23:24:09 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:10 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:10 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D","title":"free bands","artist":"heygwuapo","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-Cz76cdFb8MNWUsLb-zwwz0g-t500x500.jpg","trackType":"aac","duration":127,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":30,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:10 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:10 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:10 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:10 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:10 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:10.403+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=30
Mar 29 23:24:10 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:10.404+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D" title="free bands"
Mar 29 23:24:10 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Mar 29 23:24:10 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] adaptive demux: Changing stream format Unknown -> MP4
Mar 29 23:24:10 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b1fe0100] mp4 demux: Fragment sequence discontinuity detected 1 != 0
Mar 29 23:24:10 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] http demux error: local stream 1 error: Cancellation (0x8)
Mar 29 23:24:12 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98]
Mar 29 23:24:12 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) http demux error:
Mar 29 23:24:12 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) local stream 3 error: Cancellation (0x8)
Mar 29 23:24:12 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852)
Mar 29 23:24:12 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] http demux error: local stream 5 error: Cancellation (0x8)
Mar 29 23:24:12 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:12.652+05:30 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" latency=401.817539ms timeout=3s volume=29
Mar 29 23:24:12 volumio volumio[20612]: info: VolumeController::SetAlsaVolume29
Mar 29 23:24:12 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:12 volumio volumio[20612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 29 23:24:12 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:12 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:12.689+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=29
Mar 29 23:24:12 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:12.689+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D" title="free bands"
Mar 29 23:24:12 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29
Mar 29 23:24:12 volumio volumio[20612]: SPOTIFY: SPOTIFY VOLUME 30
Mar 29 23:24:12 volumio volumio[20612]: SPOTIFY: VOLUMIO VOLUME 29
Mar 29 23:24:12 volumio volumio[20612]: SPOTIFY: DELTA VOLUME ENOUGH: false
Mar 29 23:24:12 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] http demux error: local stream 7 error: Cancellation (0x8)
Mar 29 23:24:20 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] http demux error: local stream 9 error: Cancellation (0x8)
Mar 29 23:24:30 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] http demux error: local stream 11 error: Cancellation (0x8)
Mar 29 23:24:32 volumio go-librespot[20827]: time="2026-03-29T23:24:32+05:30" level=trace msg="sent dealer ping"
Mar 29 23:24:32 volumio go-librespot[20827]: time="2026-03-29T23:24:32+05:30" level=trace msg="received dealer pong"
Mar 29 23:24:35 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::volumioReplaceandPlayItems
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::ClearQueue
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::serviceStop
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::serviceStop
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::stPlaybackTimer
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::updateTrackBlock
Mar 29 23:24:35 volumio volumio[20612]: info: CorePlayQueue::getTrackBlock
Mar 29 23:24:35 volumio volumio[20612]: info: CorePlayQueue::clearPlayQueue
Mar 29 23:24:35 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::addQueueItems
Mar 29 23:24:35 volumio volumio[20612]: info: CorePlayQueue::addQueueItems
Mar 29 23:24:35 volumio volumio[20612]: info: Preload queue cleared
Mar 29 23:24:35 volumio volumio[20612]: info: Adding Item to queue: soundcloud/playlists@playlistId=1109034904
Mar 29 23:24:35 volumio volumio[20612]: info: Exploding uri soundcloud/playlists@playlistId=1109034904 in service soundcloud
Mar 29 23:24:35 volumio volumio[20612]: info: [soundcloud] explodeUri: soundcloud/playlists@playlistId=1109034904
Mar 29 23:24:35 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] http demux error: local stream 13 error: Cancellation (0x8)
Mar 29 23:24:35 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) [b52bee98] http demux error: local stream 1 error: Cancellation (0x8)
Mar 29 23:24:35 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Player status "stopped" - unsetting ourselves as current service...
Mar 29 23:24:35 volumio volumio[20612]: verbose: UNSET VOLATILE: Service: soundcloud
Mar 29 23:24:35 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Volatile state unset, stopping playback (if any)...
Mar 29 23:24:35 volumio volumio[20612]: info: [soundcloud] [vlc] (PID: 22852) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":29,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::servicePushState
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:35 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:35 volumio volumio[20612]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":null,"repeatSingle":false,"random":null,"volume":29,"mute":false,"disableVolumeControl":false}
Mar 29 23:24:35 volumio volumio[20612]: verbose: CURRENT POSITION 0
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::syncState stateService stop
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::syncState currentStatus stop
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:35 volumio volumio[20612]: info: No code
Mar 29 23:24:35 volumio volumio[20612]: info: CoreStateMachine::pushState
Mar 29 23:24:35 volumio volumio[20612]: info: CoreCommandRouter::volumioPushState
Mar 29 23:24:35 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:35.413+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=29
Mar 29 23:24:35 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:35.415+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D" title="free bands"
Mar 29 23:24:35 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:35.416+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=29
Mar 29 23:24:35 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:35.417+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D" title="free bands"
Mar 29 23:24:35 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:35.419+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" state=STATUS_PLAYING positionMs=0 volume=29
Mar 29 23:24:35 volumio volumio5-onboarding[1494]: time=2026-03-29T23:24:35.419+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.38:55676,00:00:00:00:00:00%03 @ 0x295e300" id="soundcloud/track@trackId=1551781249@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1690636509%7D" title="free bands"
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: SPOTIFY VOLUME 30
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: VOLUMIO VOLUME 29
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: DELTA VOLUME ENOUGH: false
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: SPOTIFY VOLUME 30
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: VOLUMIO VOLUME 29
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: DELTA VOLUME ENOUGH: false
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: SPOTIFY VOLUME 30
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: VOLUMIO VOLUME 29
Mar 29 23:24:35 volumio volumio[20612]: SPOTIFY: DELTA VOLUME ENOUGH: false
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2287357694@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2281005911@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2280992270@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2286338009@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2285236145@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2287578689@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2279009882@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2274119930@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2278225949@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2289696320@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2280631163@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2283031574@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2285461622@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2276456981@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2286842738@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2279090324@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2282292476@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2286855341@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2285465075@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2278476497@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2287880021@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2263419920@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2259176846@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2280724988@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2286240152@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2263872026@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2263872110@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2269799750@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2277073331@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2269207364@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2269153283@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2268494087@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2261024165@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2277044456@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2269913777@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2273272277@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2267625056@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2268256331@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2270540402@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2273346107@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2259121166@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2269955894@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2262932768@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2259367466@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2263296731@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2265676781@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2187425093@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: CoreCommandRouter::volumioPushQueue
Mar 29 23:24:36 volumio volumio[20612]: info: CorePlayQueue::saveQueue
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::updateTrackBlock
Mar 29 23:24:36 volumio volumio[20612]: info: CorePlayQueue::getTrackBlock
Mar 29 23:24:36 volumio volumio[20612]: info: CoreCommandRouter::volumioPlay
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::play index 0
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::stop
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::play index undefined
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 29 23:24:36 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:36 volumio volumio[20612]: info: CoreStateMachine::startPlaybackTimer
Mar 29 23:24:36 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2287357694@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1109034904%7D
Mar 29 23:24:36 volumio volumio[20612]: info: CoreCommandRouter::volumioGetState
Mar 29 23:24:36 volumio volumio[20612]: info: CorePlayQueue::getTrack 0
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] [mpv] mpv process spawned
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] Going to start mpv for playback
Mar 29 23:24:36 volumio volumio[20612]: info: [soundcloud] [mpv] (PID: 23077) /bin/sh: 1: mpv: not found
Mar 29 23:24:36 volumio volumio[20612]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 29 23:24:36 volumio volumio[20612]: TypeError: Cannot read properties of null (reading 'reset')
Mar 29 23:24:36 volumio volumio[20612]: at /data/plugins/music_service/soundcloud/node_modules/volumio-ext-players/dist/mpv/MPVService.js:170:48
Mar 29 23:24:36 volumio volumio[20612]: at ChildProcess. (/data/plugins/music_service/soundcloud/node_modules/volumio-ext-players/dist/mpv/MPVService.js:184:19)
Mar 29 23:24:36 volumio volumio[20612]: at ChildProcess.emit (node:events:514:28)
Mar 29 23:24:36 volumio volumio[20612]: at maybeClose (node:internal/child_process:1105:16)
Mar 29 23:24:36 volumio volumio[20612]: at Socket. (node:internal/child_process:457:11)
Mar 29 23:24:36 volumio volumio[20612]: at Socket.emit (node:events:514:28)
Mar 29 23:24:36 volumio volumio[20612]: at Pipe. (node:net:337:12)
Mar 29 23:24:36 volumio volumio[20612]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 29 23:24:37 volumio sudo[23093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 23:23'
Mar 29 23:24:37 volumio sudo[23093]: 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"