-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-06-21 09:40:36 CEST. -- Jun 21 09:39:08 volumio4 kernel: usb 1-1.2: USB disconnect, device number 3 Jun 21 09:39:08 volumio4 kernel: usb 1-1.2: new high-speed USB device number 4 using xhci_hcd Jun 21 09:39:08 volumio4 kernel: usb 1-1.2: New USB device found, idVendor=13fd, idProduct=1040, bcdDevice= 1.06 Jun 21 09:39:08 volumio4 kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 21 09:39:08 volumio4 kernel: usb 1-1.2: Product: DVD RW AD-7590A Jun 21 09:39:08 volumio4 kernel: usb 1-1.2: Manufacturer: Initio Jun 21 09:39:08 volumio4 kernel: usb 1-1.2: SerialNumber: 0000000000000000W Jun 21 09:39:08 volumio4 kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jun 21 09:39:08 volumio4 kernel: scsi host0: usb-storage 1-1.2:1.0 Jun 21 09:39:09 volumio4 kernel: usb 1-1.2: reset high-speed USB device number 4 using xhci_hcd Jun 21 09:39:11 volumio4 kernel: usb 1-1.2: USB disconnect, device number 4 Jun 21 09:39:32 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 21 09:39:33 volumio4 volumio[956]: info: handleBrowseUri took 164 milliseconds Jun 21 09:39:33 volumio4 volumio[956]: info: Preload queue cleared Jun 21 09:39:33 volumio4 volumio[956]: info: Preload queue cleared Jun 21 09:39:34 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jun 21 09:39:34 volumio4 volumio[956]: info: handleBrowseUri took 466 milliseconds Jun 21 09:39:35 volumio4 volumio[956]: info: Preload queue cleared Jun 21 09:39:36 volumio4 volumio[956]: info: Preload queue cleared Jun 21 09:39:36 volumio4 volumio[956]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::ClearQueue Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::stop Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 09:39:36 volumio4 volumio[956]: info: CorePlayQueue::clearPlayQueue Jun 21 09:39:36 volumio4 volumio[956]: info: CorePlayQueue::saveQueue Jun 21 09:39:36 volumio4 volumio[956]: info: CoreCommandRouter::volumioPushQueue Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::addQueueItems Jun 21 09:39:36 volumio4 volumio[956]: info: CorePlayQueue::addQueueItems Jun 21 09:39:36 volumio4 volumio[956]: info: Preload queue cleared Jun 21 09:39:36 volumio4 volumio[956]: info: Adding Item to queue: qobuz://album/kxh90hwtbwmib Jun 21 09:39:36 volumio4 volumio[956]: info: Exploding uri qobuz://album/kxh90hwtbwmib in service qobuz Jun 21 09:39:36 volumio4 volumio[956]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jun 21 09:39:36 volumio4 volumio[956]: info: explodeUri took 565 milliseconds Jun 21 09:39:36 volumio4 volumio[956]: info: CoreCommandRouter::volumioPushQueue Jun 21 09:39:36 volumio4 volumio[956]: info: CorePlayQueue::saveQueue Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::updateTrackBlock Jun 21 09:39:36 volumio4 volumio[956]: info: CorePlayQueue::getTrackBlock Jun 21 09:39:36 volumio4 volumio[956]: info: CoreCommandRouter::volumioPlay Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::play index 0 Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::stop Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::play index undefined Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 09:39:36 volumio4 volumio[956]: info: CorePlayQueue::getTrack 0 Jun 21 09:39:36 volumio4 volumio[956]: info: CoreStateMachine::startPlaybackTimer Jun 21 09:39:36 volumio4 volumio[956]: info: CorePlayQueue::getTrack 0 Jun 21 09:39:36 volumio4 volumio[956]: info: [1750491576863] ControllerQobuz::clearAddPlayTrack Jun 21 09:39:36 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 09:39:36 volumio4 volumio[956]: info: sendMpdCommand stop took 4 milliseconds Jun 21 09:39:36 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 09:39:36 volumio4 volumio[956]: info: Jun 21 09:39:36 volumio4 volumio[956]: ---------------------------- MPD announces system playlist update Jun 21 09:39:36 volumio4 volumio[956]: info: Ignoring MPD Status Update Jun 21 09:39:36 volumio4 volumio[956]: info: sendMpdCommand clear took 2 milliseconds Jun 21 09:39:36 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/317994346" Jun 21 09:39:36 volumio4 volumio[956]: error: updateQueue error: null Jun 21 09:39:36 volumio4 volumio[956]: info: ------------------------------ 4ms Jun 21 09:39:36 volumio4 volumio[956]: STREAMING PROXY: Handling url /?data=qobuz://song/317994346 Jun 21 09:39:36 volumio4 volumio[956]: info: Executing endpoint getStreamUrlqobuz Jun 21 09:39:36 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 21 09:39:37 volumio4 volumio[956]: info: getStreamUrl took 419 milliseconds Jun 21 09:39:37 volumio4 volumio[956]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=643734&eid=317994346&fmt=7&profile=raw&app_id=539451548&cid=2772106&etsp=1750495177&hmac=ngmBHwdOzcItioLYuumRMjkAzs0 Jun 21 09:39:37 volumio4 volumio[956]: STREAMING PROXY: Response: 200, length: 54516820 Jun 21 09:39:37 volumio4 volumio[956]: STREAMING PROXY: Client dropped request, destroying Jun 21 09:39:37 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/317994346" Jun 21 09:39:37 volumio4 volumio[956]: info: Jun 21 09:39:37 volumio4 volumio[956]: ---------------------------- MPD announces system playlist update Jun 21 09:39:37 volumio4 volumio[956]: info: Ignoring MPD Status Update Jun 21 09:39:37 volumio4 volumio[956]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/317994346" took 2 milliseconds Jun 21 09:39:37 volumio4 volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 09:39:37 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jun 21 09:39:37 volumio4 volumio[956]: info: ------------------------------ 4ms Jun 21 09:39:37 volumio4 volumio[956]: STREAMING PROXY: Handling url /?data=qobuz://song/317994346 Jun 21 09:39:37 volumio4 volumio[956]: info: sendMpdCommand play took 3 milliseconds Jun 21 09:39:37 volumio4 volumio[956]: info: Executing endpoint getStreamUrlqobuz Jun 21 09:39:37 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 21 09:39:37 volumio4 volumio[956]: info: getStreamUrl took 414 milliseconds Jun 21 09:39:37 volumio4 volumio[956]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=643734&eid=317994346&fmt=7&profile=raw&app_id=539451548&cid=2772106&etsp=1750495177&hmac=ngmBHwdOzcItioLYuumRMjkAzs0 Jun 21 09:39:38 volumio4 volumio[956]: STREAMING PROXY: Response: 200, length: 54516820 Jun 21 09:39:38 volumio4 volumio[956]: info: Jun 21 09:39:38 volumio4 volumio[956]: ---------------------------- MPD announces state update: player Jun 21 09:39:38 volumio4 volumio[956]: info: ControllerMpd::getState Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jun 21 09:39:38 volumio4 volumio[956]: info: Jun 21 09:39:38 volumio4 volumio[956]: ---------------------------- MPD announces state update: player Jun 21 09:39:38 volumio4 volumio[956]: info: sendMpdCommand status took 4 milliseconds Jun 21 09:39:38 volumio4 volumio[956]: info: ControllerMpd::getState Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand status Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::parseState Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 09:39:38 volumio4 volumio[956]: info: sendMpdCommand status took 4 milliseconds Jun 21 09:39:38 volumio4 volumio[956]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::parseState Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::parseTrackInfo Jun 21 09:39:38 volumio4 volumio[956]: info: ControllerMpd::pushState Jun 21 09:39:38 volumio4 volumio[956]: info: CoreCommandRouter::servicePushState Jun 21 09:39:38 volumio4 volumio[956]: info: CorePlayQueue::getTrack 0 Jun 21 09:39:38 volumio4 volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"317994346","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/317994346","trackType":"qobuz"} Jun 21 09:39:38 volumio4 volumio[956]: verbose: CURRENT POSITION 0 Jun 21 09:39:38 volumio4 volumio[956]: info: CoreStateMachine::syncState stateService play Jun 21 09:39:38 volumio4 volumio[956]: info: CoreStateMachine::syncState currentStatus stop Jun 21 09:39:38 volumio4 volumio[956]: info: ------------------------------ 18ms Jun 21 09:39:38 volumio4 volumio[956]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 21 09:39:38 volumio4 volumio[956]: verbose: ControllerMpd::parseTrackInfo Jun 21 09:39:38 volumio4 volumio[956]: info: ControllerMpd::pushState Jun 21 09:39:38 volumio4 volumio[956]: info: CoreCommandRouter::servicePushState Jun 21 09:39:38 volumio4 volumio[956]: info: CorePlayQueue::getTrack 0 Jun 21 09:39:38 volumio4 volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"317994346","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/317994346","trackType":"qobuz"} Jun 21 09:39:38 volumio4 volumio[956]: verbose: CURRENT POSITION 0 Jun 21 09:39:38 volumio4 volumio[956]: info: CoreStateMachine::syncState stateService play Jun 21 09:39:38 volumio4 volumio[956]: info: CoreStateMachine::syncState currentStatus play Jun 21 09:39:38 volumio4 volumio[956]: info: Received an update from plugin. extracting info from payload Jun 21 09:39:38 volumio4 volumio[956]: info: CoreStateMachine::pushState Jun 21 09:39:38 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 09:39:38 volumio4 volumio[956]: info: CoreCommandRouter::volumioPushState Jun 21 09:39:38 volumio4 volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jun 21 09:39:38 volumio4 volumio[956]: info: MRS: Pushing multiroomSync output Jun 21 09:39:38 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:39:38 volumio4 volumio[956]: info: CoreStateMachine::pushState Jun 21 09:39:38 volumio4 volumio[956]: info: CoreCommandRouter::volumioPushState Jun 21 09:39:38 volumio4 volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jun 21 09:39:38 volumio4 volumio[956]: info: MRS: Pushing multiroomSync output Jun 21 09:39:38 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:39:38 volumio4 volumio[956]: info: ------------------------------ 52ms Jun 21 09:39:49 volumio4 volumio[956]: STREAMING PROXY: Client dropped request, destroying Jun 21 09:39:51 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:39:53 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:39:53 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:39:53 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:39:53 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:39:53 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:39:53 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 09:39:56 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 21 09:39:56 volumio4 volumio[956]: info: Getting Alsa Cards List without I2S DAC Jun 21 09:39:56 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:39:56 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 21 09:39:56 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 21 09:39:59 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:01 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:40:01 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:40:01 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:40:01 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:40:01 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:40:01 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 09:40:03 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 21 09:40:03 volumio4 volumio[956]: info: Getting Alsa Cards List without I2S DAC Jun 21 09:40:03 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:40:03 volumio4 volumio[956]: xcb_connection_has_error() returned true Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 21 09:40:03 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 21 09:40:06 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:08 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:40:08 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:40:08 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:40:08 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:40:08 volumio4 volumio[956]: info: Executing endpoint metavolumio Jun 21 09:40:08 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 21 09:40:13 volumio4 volumio[956]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 09:40:13 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 09:40:13 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 09:40:13 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 09:40:13 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 21 09:40:13 volumio4 volumio[956]: info: Received Get System Version Jun 21 09:40:13 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 09:40:19 volumio4 volumio[956]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Jun 21 09:40:19 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Jun 21 09:40:19 volumio4 volumio[956]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Jun 21 09:40:20 volumio4 volumio[956]: info: CoreCommandRouter::Reload Ui Jun 21 09:40:22 volumio4 volumio[956]: verbose: New Socket.io Connection to 192.168.5.206 from 192.168.5.31 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 09:40:22 volumio4 volumio[956]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 21 09:40:22 volumio4 volumio[956]: info: Received Get System Info Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 09:40:22 volumio4 volumio[956]: info: Discovery: Getting this device information Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:22 volumio4 volumio[956]: info: Listing playlists Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetQueue Jun 21 09:40:22 volumio4 volumio[956]: info: CoreStateMachine::getQueue Jun 21 09:40:22 volumio4 volumio[956]: info: CorePlayQueue::getQueue Jun 21 09:40:22 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 21 09:40:23 volumio4 sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 09:40:23 volumio4 sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 09:40:23 volumio4 sudo[1899]: pam_unix(sudo:session): session closed for user root Jun 21 09:40:23 volumio4 sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 09:40:23 volumio4 sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 09:40:23 volumio4 sudo[1901]: pam_unix(sudo:session): session closed for user root Jun 21 09:40:23 volumio4 volumio[956]: verbose: New Socket.io Connection to 192.168.5.206 from 192.168.5.31 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:139.0) Gecko/20100101 Firefox/139.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 09:40:23 volumio4 volumio[956]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 09:40:23 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 21 09:40:24 volumio4 volumio[956]: info: Received Get System Version Jun 21 09:40:24 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 09:40:24 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 09:40:26 volumio4 volumio[956]: info: Received Get System Info Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 09:40:26 volumio4 volumio[956]: info: Discovery: Getting this device information Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 09:40:26 volumio4 volumio[956]: info: Received Get System Info Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 09:40:26 volumio4 volumio[956]: info: Discovery: Getting this device information Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::volumioGetState Jun 21 09:40:26 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 09:40:36 volumio4 volumio[956]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jun 21 09:40:36 volumio4 volumio[956]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jun 21 09:40:36 volumio4 volumio[956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 09:40:36 volumio4 volumio[956]: TypeError: Cannot read property 'then' of undefined Jun 21 09:40:36 volumio4 volumio[956]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jun 21 09:40:36 volumio4 volumio[956]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Jun 21 09:40:36 volumio4 volumio[956]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Jun 21 09:40:36 volumio4 volumio[956]: at Socket.emit (events.js:315:20) Jun 21 09:40:36 volumio4 volumio[956]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 21 09:40:36 volumio4 volumio[956]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 21 09:40:36 volumio4 volumio[956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 09:40:36 volumio4 sudo[1998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-21 09:39 Jun 21 09:40:36 volumio4 sudo[1998]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"