-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Sun 2025-01-19 03:46:37 PST. -- Jan 19 03:45:02 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 03:45:06 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 03:45:10 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 03:45:19 volumio volumio[7697]: info: Received OAUTH Data Jan 19 03:45:19 volumio volumio[7697]: info: Refreshing TIDAL token Jan 19 03:45:19 volumio volumio[7697]: info: TIDAL Access Token successfully retrieved Jan 19 03:45:19 volumio sudo[9355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 03:45:19 volumio sudo[9355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:45:20 volumio sudo[9357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 03:45:20 volumio sudo[9357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:45:20 volumio sudo[9357]: pam_unix(sudo:session): session closed for user root Jan 19 03:45:20 volumio sudo[9355]: pam_unix(sudo:session): session closed for user root Jan 19 03:45:20 volumio volumio[7697]: verbose: New Socket.io Connection to 192.168.4.152 from 192.168.4.63 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 19 03:45:20 volumio volumio[7697]: info: Successfully retrieved User Session From TIDAL Jan 19 03:45:20 volumio volumio[7697]: info: Adding TIDAL to Browse Sources Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 03:45:20 volumio volumio[7697]: info: [1737287120160] CoreMusicLibrary::Adding element TIDAL Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 03:45:20 volumio volumio[7697]: Cannot find translation for source HiFiBerry ADC Jan 19 03:45:20 volumio volumio[7697]: Cannot find translation for source TIDAL Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioGetVisibleSources Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 03:45:20 volumio volumio[7697]: info: Retrieving Cloud Streaming UI Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 19 03:45:20 volumio volumio[7697]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 19 03:45:20 volumio volumio[7697]: info: Received Get System Info Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 03:45:20 volumio volumio[7697]: info: Discovery: Getting this device information Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:45:20 volumio volumio[7697]: info: Listing playlists Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 03:45:20 volumio volumio[7697]: info: Getting Tidal Cloud Configuration Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 03:45:20 volumio volumio[7697]: info: Getting Qobuz Cloud Configuration Jan 19 03:45:20 volumio volumio[7697]: info: Asking plugin for UI Config Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 03:45:20 volumio volumio[7697]: info: Getting Spotify Cloud Configuration Jan 19 03:45:20 volumio volumio[7697]: info: Asking plugin for UI Config Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 03:45:20 volumio volumio[7697]: info: Saving Spotify Acccount Jan 19 03:45:20 volumio volumio[7697]: info: Got it Jan 19 03:45:20 volumio volumio[7697]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 19 03:45:20 volumio volumio[7697]: info: Got Tidal Cloud Configuration Jan 19 03:45:20 volumio volumio[7697]: info: Got it Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 03:45:20 volumio volumio[7697]: info: Successfully retrieved User Subscription From TIDAL Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 03:45:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 19 03:45:21 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 19 03:45:21 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 03:45:21 volumio volumio[7697]: info: Received Get System Info Jan 19 03:45:21 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 03:45:21 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 03:45:21 volumio volumio[7697]: info: Discovery: Getting this device information Jan 19 03:45:21 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:45:21 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 03:45:22 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 03:45:22 volumio volumio[7697]: info: Received Get System Info Jan 19 03:45:22 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 03:45:22 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 03:45:22 volumio volumio[7697]: info: Discovery: Getting this device information Jan 19 03:45:22 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:45:22 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 03:45:23 volumio volumio[7697]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jan 19 03:45:23 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jan 19 03:45:23 volumio volumio[7697]: info: Saving configuration Jan 19 03:45:23 volumio volumio[7697]: info: Done Jan 19 03:45:29 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:45:30 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 19 03:45:31 volumio volumio[7697]: info: CoreCommandRouter::volumioGetQueue Jan 19 03:45:31 volumio volumio[7697]: info: CoreStateMachine::getQueue Jan 19 03:45:31 volumio volumio[7697]: info: CorePlayQueue::getQueue Jan 19 03:45:33 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 19 03:45:33 volumio volumio[7697]: info: browseTIDALUri took 244 milliseconds Jan 19 03:45:33 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:33 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:37 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 19 03:45:37 volumio volumio[7697]: info: browseTIDALUri took 368 milliseconds Jan 19 03:45:37 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:38 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 03:45:38 volumio volumio[7697]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 19 03:45:38 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 19 03:45:38 volumio volumio[7697]: info: Received Get System Version Jan 19 03:45:38 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 03:45:38 volumio volumio[7697]: info: Received Get System Info Jan 19 03:45:38 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 03:45:38 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 03:45:38 volumio volumio[7697]: info: Discovery: Getting this device information Jan 19 03:45:38 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:45:38 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 03:45:41 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 19 03:45:41 volumio volumio[7697]: info: browseTIDALUri took 324 milliseconds Jan 19 03:45:41 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:41 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:43 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 19 03:45:43 volumio volumio[7697]: info: browseTIDALUri took 298 milliseconds Jan 19 03:45:43 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:43 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:47 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 19 03:45:47 volumio volumio[7697]: info: browseTIDALUri took 256 milliseconds Jan 19 03:45:47 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:47 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:50 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 19 03:45:51 volumio volumio[7697]: info: browseTIDALUri took 904 milliseconds Jan 19 03:45:51 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:51 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:51 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/17662141 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157051 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157094 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157060 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157045 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157110 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622289 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157046 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/1541980 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/148187 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/148192 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/1794659 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622295 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622290 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/17662140 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/245030392 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622294 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/17662144 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/148190 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157108 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622280 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/1794654 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157130 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/63889770 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157092 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622282 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/245030381 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/245030383 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/105509523 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157091 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622291 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/56058464 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157138 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/17662146 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622297 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157047 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622293 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622292 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157048 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157063 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157093 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622278 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/157065 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/17662142 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/245030384 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/17662143 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/77622296 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/245030382 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/56058463 Jan 19 03:45:51 volumio volumio[7697]: info: Preloading song: tidal://song/56058470 Jan 19 03:45:51 volumio volumio[7697]: info: Exploding uri tidal://song/17662141 in service tidal Jan 19 03:45:51 volumio volumio[7697]: info: Exploding uri tidal://song/157051 in service tidal Jan 19 03:45:51 volumio volumio[7697]: info: Exploding uri tidal://song/157094 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/157060 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/157045 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/157110 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/77622289 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 314 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 274 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/157046 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 262 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 247 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/1541980 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/148187 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/148192 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 268 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/1794659 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 281 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 387 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/77622295 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/77622290 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 278 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/17662140 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 290 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/245030392 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 261 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 417 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/77622294 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/17662144 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 302 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/148190 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 286 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 341 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/157108 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 286 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/77622280 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 285 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/1794654 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: Exploding uri tidal://song/157130 in service tidal Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 303 milliseconds Jan 19 03:45:52 volumio volumio[7697]: info: explodeTIDALUri took 267 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/63889770 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 247 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 249 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157092 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/77622282 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/245030381 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 284 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 271 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/245030383 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 261 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/105509523 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 278 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157091 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 283 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 255 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/77622291 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/56058464 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 245 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157138 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 238 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/17662146 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 391 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 256 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/77622297 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157047 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 280 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/77622293 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 266 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/77622292 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157048 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 295 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157063 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 381 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 291 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157093 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 255 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: explodeTIDALUri took 250 milliseconds Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/77622278 in service tidal Jan 19 03:45:53 volumio volumio[7697]: info: Exploding uri tidal://song/157065 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 289 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: Exploding uri tidal://song/17662142 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: Exploding uri tidal://song/245030384 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: Exploding uri tidal://song/17662143 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 362 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 311 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: Exploding uri tidal://song/77622296 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 377 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: Exploding uri tidal://song/245030382 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 262 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 340 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: Exploding uri tidal://song/56058463 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 268 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: Exploding uri tidal://song/56058470 in service tidal Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 267 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 267 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 403 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 261 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 282 milliseconds Jan 19 03:45:54 volumio volumio[7697]: info: explodeTIDALUri took 254 milliseconds Jan 19 03:46:03 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 19 03:46:04 volumio volumio[7697]: info: browseTIDALUri took 302 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157049 Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157050 Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157052 Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157053 Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157054 Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157055 Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157056 Jan 19 03:46:04 volumio volumio[7697]: info: Preloading song: tidal://song/157057 Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157049 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: Executing endpoint getSimilarAlbums Jan 19 03:46:04 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157050 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157052 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: Executing endpoint metavolumio Jan 19 03:46:04 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 03:46:04 volumio volumio[7697]: info: Executing endpoint metavolumio Jan 19 03:46:04 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157053 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157054 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157055 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157056 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 315 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 293 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: Exploding uri tidal://song/157057 in service tidal Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 352 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 330 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 508 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 281 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 477 milliseconds Jan 19 03:46:04 volumio volumio[7697]: info: explodeTIDALUri took 417 milliseconds Jan 19 03:46:11 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::ClearQueue Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::stop Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::serviceStop Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::serviceStop Jan 19 03:46:11 volumio volumio[7697]: info: INPUTS, stopping input: audio_input Jan 19 03:46:11 volumio volumio[7697]: info: Disabiling Audio Input 2 Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::clearPlayQueue Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::saveQueue Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::volumioPushQueue Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::addQueueItems Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::addQueueItems Jan 19 03:46:11 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157045 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157045 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157046 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157046 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157047 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157047 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157048 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157048 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157049 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157049 Jan 19 03:46:11 volumio sudo[9501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audioinput.service Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::volumioPushQueue Jan 19 03:46:11 volumio sudo[9501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::saveQueue Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::updateTrackBlock Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::getTrackBlock Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::volumioPlay Jan 19 03:46:11 volumio volumio[7697]: verbose: UNSET VOLATILE: Service: smart_inputs Jan 19 03:46:11 volumio volumio[7697]: info: Disabiling Audio Input 2 Jan 19 03:46:11 volumio systemd[1]: Stopping Audio Input Service... Jan 19 03:46:11 volumio systemd[1]: audioinput.service: Main process exited, code=killed, status=15/TERM Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::play index 4 Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::addQueueItems Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::addQueueItems Jan 19 03:46:11 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157050 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157050 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157051 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157051 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157052 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157052 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157053 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157053 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157054 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157054 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157055 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157055 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157056 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157056 Jan 19 03:46:11 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157057 Jan 19 03:46:11 volumio volumio[7697]: info: Using cached record of: tidal://song/157057 Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::stop Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::stPlaybackTimer Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::updateTrackBlock Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::getTrackBlock Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::pushState Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::getTrack 0 Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::volumioPushState Jan 19 03:46:11 volumio systemd[1]: audioinput.service: Succeeded. Jan 19 03:46:11 volumio systemd[1]: Stopped Audio Input Service. Jan 19 03:46:11 volumio sudo[9504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop audioinput.service Jan 19 03:46:11 volumio sudo[9504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:11 volumio volumio[7697]: info: MRS: Pushing multiroomSync output update for this device Jan 19 03:46:11 volumio volumio[7697]: info: MRS: Pushing multiroomSync output Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::serviceStop Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::getTrack 0 Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::serviceStop Jan 19 03:46:11 volumio volumio[7697]: info: [1737287171547] ControllerTidal::stop Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 03:46:11 volumio volumio[7697]: info: ControllerMpd::stop Jan 19 03:46:11 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::volumioPushQueue Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::saveQueue Jan 19 03:46:11 volumio sudo[9501]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::updateTrackBlock Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::getTrackBlock Jan 19 03:46:11 volumio sudo[9504]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:11 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:11 volumio volumio[7697]: info: sendMpdCommand stop took 105 milliseconds Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::play index undefined Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::getTrack 4 Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::startPlaybackTimer Jan 19 03:46:11 volumio volumio[7697]: info: CorePlayQueue::getTrack 4 Jan 19 03:46:11 volumio volumio[7697]: info: [1737287171661] ControllerTidal::clearAddPlayTrack Jan 19 03:46:11 volumio volumio[7697]: info: Getting stream with soundQuality LOSSLESS Jan 19 03:46:11 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 03:46:11 volumio volumio[7697]: info: Successfully Stopped Audio Input Jan 19 03:46:11 volumio volumio[7697]: info: Successfully Stopped Audio Input Jan 19 03:46:11 volumio volumio[7697]: info: sendMpdCommand stop took 6 milliseconds Jan 19 03:46:11 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 03:46:11 volumio volumio[7697]: info: Jan 19 03:46:11 volumio volumio[7697]: ---------------------------- MPD announces system playlist update Jan 19 03:46:11 volumio volumio[7697]: info: Ignoring MPD Status Update Jan 19 03:46:11 volumio volumio[7697]: info: sendMpdCommand clear took 6 milliseconds Jan 19 03:46:11 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/157049&soundQuality=LOSSLESS" Jan 19 03:46:11 volumio volumio[7697]: error: updateQueue error: null Jan 19 03:46:11 volumio volumio[7697]: info: Jan 19 03:46:11 volumio volumio[7697]: ---------------------------- MPD announces system playlist update Jan 19 03:46:11 volumio volumio[7697]: info: Ignoring MPD Status Update Jan 19 03:46:11 volumio volumio[7697]: info: ------------------------------ 11ms Jan 19 03:46:11 volumio volumio[7697]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/157049&soundQuality=LOSSLESS" took 8 milliseconds Jan 19 03:46:11 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 03:46:11 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand play Jan 19 03:46:11 volumio volumio[7697]: info: ------------------------------ 8ms Jan 19 03:46:11 volumio volumio[7697]: info: sendMpdCommand play took 6 milliseconds Jan 19 03:46:11 volumio volumio[7697]: STREAMING PROXY: Handling url /?data=tidal://song/157049&soundQuality=LOSSLESS Jan 19 03:46:11 volumio volumio[7697]: info: Executing endpoint getStreamUrltidal Jan 19 03:46:11 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 19 03:46:12 volumio volumio[7697]: info: getStreamUrl took 259 milliseconds Jan 19 03:46:12 volumio volumio[7697]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicyNWIxMGY4NThmMjBlNjFiOTFlOWRiNTU0OGNlNGViZF82MS5tcDQ/0.flac?Expires=1737290771&Signature=pmdg728iVqQtR0AWeR5JZEyCV1hML6xyrtNTRWUcwsLVnBUqAmmRx8hUd-~786uOMiC6S5Lrj56JitaOLIQcBjTdmRTjOi7SGysA8lYshD3y84Hs~Q3UwVIV0ygT2YXDYU2Wq2t0KcMSqmSPiwf0jJGGkCHnHO9kw0thY02BMCkQBcNkRCFlJmELXDGN6sdeRozTXsRDwDqqwWP7BGr-r5Ozxi2T~v31wRetcb-ZtuwnK5yFuETiFlo0ySBGMWqOvt2mxdqEt7OAdyisp5g20x-eHAs1VMvF6aNMVllC0efEK9qMvir5l1bk0Z8X3b8wjTrJ3xve1b~fG4LU9GSN9Q__&Key-Pair-Id=K14LZCZ9QUI4JL Jan 19 03:46:12 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:46:12 volumio volumio[7697]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::ClearQueue Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::stop Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::clearPlayQueue Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::saveQueue Jan 19 03:46:12 volumio volumio[7697]: info: CoreCommandRouter::volumioPushQueue Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::addQueueItems Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::addQueueItems Jan 19 03:46:12 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157045 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157045 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157046 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157046 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157047 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157047 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157048 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157048 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157049 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157049 Jan 19 03:46:12 volumio volumio[7697]: info: CoreCommandRouter::volumioPushQueue Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::saveQueue Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::updateTrackBlock Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::getTrackBlock Jan 19 03:46:12 volumio volumio[7697]: info: CoreCommandRouter::volumioPlay Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::play index 4 Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::addQueueItems Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::addQueueItems Jan 19 03:46:12 volumio volumio[7697]: info: Preload queue cleared Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157050 Jan 19 03:46:12 volumio volumio[7697]: STREAMING PROXY: Response: 200, length: 21909670 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157050 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157051 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157051 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157052 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157052 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157053 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157053 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157054 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157054 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157055 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157055 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157056 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157056 Jan 19 03:46:12 volumio volumio[7697]: info: Adding Item to queue: tidal://song/157057 Jan 19 03:46:12 volumio volumio[7697]: info: Using cached record of: tidal://song/157057 Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::stop Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 03:46:12 volumio volumio[7697]: info: CoreCommandRouter::volumioPushQueue Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::saveQueue Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::play index undefined Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::updateTrackBlock Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::getTrackBlock Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::getTrack 4 Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::startPlaybackTimer Jan 19 03:46:12 volumio volumio[7697]: info: CorePlayQueue::getTrack 4 Jan 19 03:46:12 volumio volumio[7697]: info: [1737287172466] ControllerTidal::clearAddPlayTrack Jan 19 03:46:12 volumio volumio[7697]: info: Getting stream with soundQuality LOSSLESS Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 03:46:12 volumio volumio[7697]: STREAMING PROXY: Client dropped request, destroying Jan 19 03:46:12 volumio volumio[7697]: info: Jan 19 03:46:12 volumio volumio[7697]: ---------------------------- MPD announces state update: player Jan 19 03:46:12 volumio volumio[7697]: info: sendMpdCommand stop took 11 milliseconds Jan 19 03:46:12 volumio volumio[7697]: info: ControllerMpd::getState Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand status Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 03:46:12 volumio volumio[7697]: info: Jan 19 03:46:12 volumio volumio[7697]: ---------------------------- MPD announces system playlist update Jan 19 03:46:12 volumio volumio[7697]: info: Ignoring MPD Status Update Jan 19 03:46:12 volumio volumio[7697]: info: sendMpdCommand status took 5 milliseconds Jan 19 03:46:12 volumio volumio[7697]: info: sendMpdCommand clear took 4 milliseconds Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::parseState Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/157049&soundQuality=LOSSLESS" Jan 19 03:46:12 volumio volumio[7697]: error: updateQueue error: null Jan 19 03:46:12 volumio volumio[7697]: info: Jan 19 03:46:12 volumio volumio[7697]: ---------------------------- MPD announces system playlist update Jan 19 03:46:12 volumio volumio[7697]: info: Ignoring MPD Status Update Jan 19 03:46:12 volumio volumio[7697]: info: ------------------------------ 12ms Jan 19 03:46:12 volumio volumio[7697]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 19 03:46:12 volumio volumio[7697]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/157049&soundQuality=LOSSLESS" took 5 milliseconds Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::parseTrackInfo Jan 19 03:46:12 volumio volumio[7697]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 03:46:12 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand play Jan 19 03:46:12 volumio volumio[7697]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 19 03:46:12 volumio volumio[7697]: info: ------------------------------ 23ms Jan 19 03:46:12 volumio volumio[7697]: info: ------------------------------ 10ms Jan 19 03:46:12 volumio volumio[7697]: info: sendMpdCommand play took 4 milliseconds Jan 19 03:46:12 volumio volumio[7697]: STREAMING PROXY: Handling url /?data=tidal://song/157049&soundQuality=LOSSLESS Jan 19 03:46:12 volumio volumio[7697]: info: Executing endpoint getStreamUrltidal Jan 19 03:46:12 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 19 03:46:12 volumio volumio[7697]: info: getStreamUrl took 362 milliseconds Jan 19 03:46:12 volumio volumio[7697]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicyNWIxMGY4NThmMjBlNjFiOTFlOWRiNTU0OGNlNGViZF82MS5tcDQ/0.flac?Expires=1737290771&Signature=pmdg728iVqQtR0AWeR5JZEyCV1hML6xyrtNTRWUcwsLVnBUqAmmRx8hUd-~786uOMiC6S5Lrj56JitaOLIQcBjTdmRTjOi7SGysA8lYshD3y84Hs~Q3UwVIV0ygT2YXDYU2Wq2t0KcMSqmSPiwf0jJGGkCHnHO9kw0thY02BMCkQBcNkRCFlJmELXDGN6sdeRozTXsRDwDqqwWP7BGr-r5Ozxi2T~v31wRetcb-ZtuwnK5yFuETiFlo0ySBGMWqOvt2mxdqEt7OAdyisp5g20x-eHAs1VMvF6aNMVllC0efEK9qMvir5l1bk0Z8X3b8wjTrJ3xve1b~fG4LU9GSN9Q__&Key-Pair-Id=K14LZCZ9QUI4JL Jan 19 03:46:13 volumio volumio[7697]: STREAMING PROXY: Response: 200, length: 21909670 Jan 19 03:46:13 volumio volumio[7697]: info: Jan 19 03:46:13 volumio volumio[7697]: ---------------------------- MPD announces state update: player Jan 19 03:46:13 volumio volumio[7697]: info: ControllerMpd::getState Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand status Jan 19 03:46:13 volumio volumio[7697]: info: Jan 19 03:46:13 volumio volumio[7697]: ---------------------------- MPD announces state update: player Jan 19 03:46:13 volumio volumio[7697]: info: sendMpdCommand status took 4 milliseconds Jan 19 03:46:13 volumio volumio[7697]: info: ControllerMpd::getState Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand status Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::parseState Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 03:46:13 volumio volumio[7697]: info: sendMpdCommand status took 3 milliseconds Jan 19 03:46:13 volumio volumio[7697]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::parseState Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::parseTrackInfo Jan 19 03:46:13 volumio volumio[7697]: info: ControllerMpd::pushState Jan 19 03:46:13 volumio volumio[7697]: info: CoreCommandRouter::servicePushState Jan 19 03:46:13 volumio volumio[7697]: info: CorePlayQueue::getTrack 4 Jan 19 03:46:13 volumio volumio[7697]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"157049&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/157049&soundQuality=LOSSLESS","trackType":"tidal"} Jan 19 03:46:13 volumio volumio[7697]: verbose: CURRENT POSITION 4 Jan 19 03:46:13 volumio volumio[7697]: info: CoreStateMachine::syncState stateService play Jan 19 03:46:13 volumio volumio[7697]: info: CoreStateMachine::syncState currentStatus stop Jan 19 03:46:13 volumio volumio[7697]: info: ------------------------------ 20ms Jan 19 03:46:13 volumio volumio[7697]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 19 03:46:13 volumio volumio[7697]: verbose: ControllerMpd::parseTrackInfo Jan 19 03:46:13 volumio volumio[7697]: info: ControllerMpd::pushState Jan 19 03:46:13 volumio volumio[7697]: info: CoreCommandRouter::servicePushState Jan 19 03:46:13 volumio volumio[7697]: info: CorePlayQueue::getTrack 4 Jan 19 03:46:13 volumio volumio[7697]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"157049&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/157049&soundQuality=LOSSLESS","trackType":"tidal"} Jan 19 03:46:13 volumio volumio[7697]: verbose: CURRENT POSITION 4 Jan 19 03:46:13 volumio volumio[7697]: info: CoreStateMachine::syncState stateService play Jan 19 03:46:13 volumio volumio[7697]: info: CoreStateMachine::syncState currentStatus play Jan 19 03:46:13 volumio volumio[7697]: info: Received an update from plugin. extracting info from payload Jan 19 03:46:13 volumio volumio[7697]: info: CoreStateMachine::pushState Jan 19 03:46:13 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 03:46:13 volumio volumio[7697]: info: CoreCommandRouter::volumioPushState Jan 19 03:46:13 volumio volumio[7697]: info: MRS: Pushing multiroomSync output update for this device Jan 19 03:46:13 volumio volumio[7697]: info: MRS: Pushing multiroomSync output Jan 19 03:46:13 volumio volumio[7697]: info: CoreStateMachine::pushState Jan 19 03:46:13 volumio volumio[7697]: info: CoreCommandRouter::volumioPushState Jan 19 03:46:13 volumio volumio[7697]: info: MRS: Pushing multiroomSync output update for this device Jan 19 03:46:13 volumio volumio[7697]: info: MRS: Pushing multiroomSync output Jan 19 03:46:13 volumio volumio[7697]: info: ------------------------------ 84ms Jan 19 03:46:18 volumio volumio[7697]: info: CoreCommandRouter::volumioGetState Jan 19 03:46:20 volumio volumio[7697]: STREAMING PROXY: Client dropped request, destroying Jan 19 03:46:20 volumio volumio[7697]: info: Executing endpoint metavolumio Jan 19 03:46:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 03:46:20 volumio volumio[7697]: info: Executing endpoint metavolumio Jan 19 03:46:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 03:46:20 volumio volumio[7697]: info: Executing endpoint metavolumio Jan 19 03:46:20 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 03:46:26 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 03:46:26 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 19 03:46:31 volumio ntpd[657]: 148.113.194.34 local addr 192.168.4.152 -> Jan 19 03:46:33 volumio volumio[7697]: info: Enabling plugin fusiondsp Jan 19 03:46:33 volumio volumio[7697]: info: Loading plugin "fusiondsp"... Jan 19 03:46:33 volumio volumio[7697]: info: Preparing to generate the ALSA configuration file Jan 19 03:46:34 volumio volumio[7697]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 19 03:46:34 volumio volumio[7697]: info: Reading ALSA contributions from plugins. Jan 19 03:46:34 volumio volumio[7697]: info: Asound.conf file unchanged, so no further update is needed Jan 19 03:46:34 volumio volumio[7697]: info: Output device has changed, restarting MPD Jan 19 03:46:34 volumio volumio[7697]: info: Output device has changed, restarting Shairport Sync Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 03:46:34 volumio sudo[9569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 03:46:34 volumio sudo[9569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:34 volumio sudo[9571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 03:46:34 volumio sudo[9569]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:34 volumio sudo[9571]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:34 volumio systemd[1]: Stopping Music Player Daemon... Jan 19 03:46:34 volumio systemd[1]: mpd.service: Succeeded. Jan 19 03:46:34 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 03:46:34 volumio volumio[7697]: xcb_connection_has_error() returned true Jan 19 03:46:34 volumio volumio[7697]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 03:46:34 volumio volumio[7697]: xcb_connection_has_error() returned true Jan 19 03:46:34 volumio volumio[7697]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: PLUGIN START: fusiondsp Jan 19 03:46:34 volumio volumio[7697]: info: Loading i18n strings for locale en Jan 19 03:46:34 volumio systemd[1]: Starting Music Player Daemon... Jan 19 03:46:34 volumio vtcs[8105]: [2025-01-19 03:46:34.448] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jan 19 03:46:34 volumio vtcs[8105]: [2025-01-19 03:46:34.450] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Jan 19 03:46:34 volumio vtcs[8105]: [2025-01-19 03:46:34.450] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jan 19 03:46:34 volumio sudo[9581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 19 03:46:34 volumio sudo[9581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: FusionDsp - mixtype--------------------- None Jan 19 03:46:34 volumio volumio[7697]: info: Preparing to generate the ALSA configuration file Jan 19 03:46:34 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jan 19 03:46:34 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 19 03:46:34 volumio systemd[1]: vtcs.service: Succeeded. Jan 19 03:46:34 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jan 19 03:46:34 volumio sudo[9581]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:34 volumio volumio[7697]: info: Done. Jan 19 03:46:34 volumio volumio[7697]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 19 03:46:34 volumio volumio[7697]: info: Reading ALSA contributions from plugins. Jan 19 03:46:34 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:34 volumio sudo[9585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 19 03:46:34 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 03:46:34 volumio sudo[9585]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:34 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:34 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:34 volumio sudo[9577]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 03:46:34 volumio sudo[9577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:34 volumio volumio[7697]: info: MPD Permissions set Jan 19 03:46:34 volumio sudo[9577]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:34 volumio sudo[9585]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 03:46:34 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: Starting Shairport Sync Jan 19 03:46:35 volumio sudo[9595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 03:46:35 volumio sudo[9595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 03:46:35 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 19 03:46:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 03:46:35 volumio volumio[7697]: info: Asound.conf file unchanged, so no further update is needed Jan 19 03:46:35 volumio volumio[7697]: info: Output device has changed, restarting MPD Jan 19 03:46:35 volumio sudo[9599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 03:46:35 volumio sudo[9599]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 03:46:35 volumio volumio[7697]: info: Output device has changed, restarting Shairport Sync Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 03:46:35 volumio sudo[9599]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:35 volumio sudo[9595]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:35 volumio sudo[9602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 03:46:35 volumio sudo[9602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 19 03:46:35 volumio systemd[1]: mpd.service: Succeeded. Jan 19 03:46:35 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 03:46:35 volumio systemd[1]: Starting Music Player Daemon... Jan 19 03:46:35 volumio volumio[7697]: xcb_connection_has_error() returned true Jan 19 03:46:35 volumio volumio[7697]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 03:46:35 volumio volumio[7697]: xcb_connection_has_error() returned true Jan 19 03:46:35 volumio volumio[7697]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio sudo[9613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 19 03:46:35 volumio sudo[9613]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:35 volumio sudo[9616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 19 03:46:35 volumio sudo[9616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:35 volumio sudo[9613]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:35 volumio volumio[7697]: info: MPD Permissions set Jan 19 03:46:35 volumio volumio[7697]: info: Shairport-Sync Started Jan 19 03:46:35 volumio sudo[9609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 03:46:35 volumio sudo[9609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:35 volumio sudo[9609]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:35 volumio volumio[7697]: info: Starting Shairport Sync Jan 19 03:46:35 volumio sudo[9616]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:35 volumio sudo[9627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 03:46:35 volumio sudo[9627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 03:46:35 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 19 03:46:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 19 03:46:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 03:46:35 volumio sudo[9627]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 03:46:35 volumio volumio[7697]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 03:46:35 volumio volumio[7697]: info: Shairport-Sync Started Jan 19 03:46:36 volumio volumio[7697]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jan 19 03:46:36 volumio volumio[7697]: info: camilladsp service started and running in background, instance 1 Jan 19 03:46:36 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 03:46:36 volumio volumio[7697]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 19 03:46:36 volumio volumio[7697]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 19 03:46:36 volumio volumio[7697]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 19 03:46:36 volumio volumio[7697]: info: FusionDsp loaded Jan 19 03:46:36 volumio volumio[7697]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 03:46:36 volumio sudo[9643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 19 03:46:36 volumio sudo[9643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 03:46:36 volumio volumio[7697]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 19 03:46:36 volumio volumio[7697]: info: Adding Signal Path Element [object Object] Jan 19 03:46:36 volumio volumio[7697]: info: Adding fusiondspeq DSP Signal Path Element Jan 19 03:46:36 volumio volumio[7697]: info: FusionDsp - ---- installed callbackRead Jan 19 03:46:36 volumio volumio[7697]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 03:46:36 volumio volumio[7697]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 19 03:46:36 volumio volumio[7697]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jan 19 03:46:36 volumio volumio[7697]: at onErrorNT (internal/child_process.js:465:16) Jan 19 03:46:36 volumio volumio[7697]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jan 19 03:46:36 volumio volumio[7697]: errno: -2, Jan 19 03:46:36 volumio volumio[7697]: code: 'ENOENT', Jan 19 03:46:36 volumio volumio[7697]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 19 03:46:36 volumio volumio[7697]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 19 03:46:36 volumio volumio[7697]: spawnargs: [ Jan 19 03:46:36 volumio volumio[7697]: '-p', Jan 19 03:46:36 volumio volumio[7697]: 9876, Jan 19 03:46:36 volumio volumio[7697]: '-o', Jan 19 03:46:36 volumio volumio[7697]: '/tmp/camilladsp.log', Jan 19 03:46:36 volumio volumio[7697]: '-l', Jan 19 03:46:36 volumio volumio[7697]: 'warn', Jan 19 03:46:36 volumio volumio[7697]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 19 03:46:36 volumio volumio[7697]: ] Jan 19 03:46:36 volumio volumio[7697]: } Jan 19 03:46:36 volumio volumio[7697]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 03:46:36 volumio sudo[9643]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:37 volumio mpd[9621]: Jan 19 03:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 03:46:37 volumio systemd[1]: Started Music Player Daemon. Jan 19 03:46:37 volumio sudo[9602]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:37 volumio sudo[9571]: pam_unix(sudo:session): session closed for user root Jan 19 03:46:37 volumio sudo[9656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 03:45 Jan 19 03:46:37 volumio sudo[9656]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"