-- Logs begin at Sat 2025-03-15 18:50:23 JST, end at Sat 2025-03-15 21:39:34 JST. -- Mar 15 21:38:07 primo volumio[3174]: info: CorePlayQueue::getTrack 2 Mar 15 21:38:07 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:07 primo volumio[3174]: info: Prefetching next song Mar 15 21:38:07 primo volumio[3174]: info: [1742042287929] ControllerQobuz::prefetch Mar 15 21:38:07 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/281303549" Mar 15 21:38:07 primo volumio[3174]: STREAMING PROXY: Handling url /?data=qobuz://song/281303549 Mar 15 21:38:07 primo volumio[3174]: info: Executing endpoint getStreamUrlqobuz Mar 15 21:38:07 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 15 21:38:08 primo volumio[3174]: info: getStreamUrl took 760 milliseconds Mar 15 21:38:08 primo volumio[3174]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3330696&eid=281303549&fmt=7&profile=raw&app_id=539451548&cid=2385490&etsp=1742045888&hmac=IrJZyaR_KoOiVVJG_lsisFWphMw Mar 15 21:38:10 primo volumio[3174]: STREAMING PROXY: Response: 200, length: 92547130 Mar 15 21:38:10 primo volumio[3174]: STREAMING PROXY: Client dropped request, destroying Mar 15 21:38:10 primo mpd[3840]: exception: No such playlist Mar 15 21:38:10 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/281303549" Mar 15 21:38:10 primo volumio[3174]: info: Mar 15 21:38:10 primo volumio[3174]: ---------------------------- MPD announces system playlist update Mar 15 21:38:10 primo volumio[3174]: info: Ignoring MPD Status Update Mar 15 21:38:10 primo volumio[3174]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/281303549" took 7 milliseconds Mar 15 21:38:10 primo volumio[3174]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 15 21:38:10 primo volumio[3174]: STREAMING PROXY: Handling url /?data=qobuz://song/281303549 Mar 15 21:38:10 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 15 21:38:10 primo volumio[3174]: info: Mar 15 21:38:10 primo volumio[3174]: ---------------------------- MPD announces system playlist update Mar 15 21:38:10 primo volumio[3174]: info: Ignoring MPD Status Update Mar 15 21:38:10 primo volumio[3174]: info: Mar 15 21:38:10 primo volumio[3174]: ---------------------------- MPD announces system playlist update Mar 15 21:38:10 primo volumio[3174]: info: Ignoring MPD Status Update Mar 15 21:38:10 primo volumio[3174]: info: ------------------------------ 9ms Mar 15 21:38:10 primo volumio[3174]: info: sendMpdCommand consume 1 took 9 milliseconds Mar 15 21:38:10 primo volumio[3174]: info: ------------------------------ 8ms Mar 15 21:38:10 primo volumio[3174]: info: ------------------------------ 4ms Mar 15 21:38:10 primo volumio[3174]: info: Executing endpoint getStreamUrlqobuz Mar 15 21:38:10 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 15 21:38:11 primo volumio[3174]: info: getStreamUrl took 876 milliseconds Mar 15 21:38:11 primo volumio[3174]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3330696&eid=281303549&fmt=7&profile=raw&app_id=539451548&cid=2385490&etsp=1742045891&hmac=SBNVvSwmMTVIsvVbkNu1RY6bMJ8 Mar 15 21:38:11 primo volumio[3174]: STREAMING PROXY: Response: 200, length: 92547130 Mar 15 21:38:12 primo volumio[3174]: info: CoreStateMachine::startPlaybackTimer Mar 15 21:38:12 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:12 primo volumio[3174]: info: CoreStateMachine::pushState Mar 15 21:38:12 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:12 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 21:38:12 primo volumio[3174]: info: CoreCommandRouter::volumioPushState Mar 15 21:38:12 primo volumio[3174]: info: MRS: Pushing multiroomSync output update for this device Mar 15 21:38:12 primo volumio[3174]: info: MRS: Pushing multiroomSync output Mar 15 21:38:12 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:12 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:12 primo volumio[3174]: info: Updating RAAT Signal Path Mar 15 21:38:13 primo mpd[3840]: player: played "http://127.0.0.1:3245?data=qobuz://song/281303548" Mar 15 21:38:13 primo volumio[3174]: info: Mar 15 21:38:13 primo volumio[3174]: ---------------------------- MPD announces system playlist update Mar 15 21:38:13 primo volumio[3174]: info: Ignoring MPD Status Update Mar 15 21:38:13 primo volumio[3174]: info: Mar 15 21:38:13 primo volumio[3174]: ---------------------------- MPD announces state update: player Mar 15 21:38:13 primo volumio[3174]: info: ControllerMpd::getState Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand status Mar 15 21:38:13 primo volumio[3174]: info: Mar 15 21:38:13 primo volumio[3174]: ---------------------------- MPD announces system playlist update Mar 15 21:38:13 primo volumio[3174]: info: Ignoring MPD Status Update Mar 15 21:38:13 primo volumio[3174]: info: Mar 15 21:38:13 primo volumio[3174]: ---------------------------- MPD announces state update: player Mar 15 21:38:13 primo volumio[3174]: info: ControllerMpd::getState Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand status Mar 15 21:38:13 primo volumio[3174]: info: Mar 15 21:38:13 primo volumio[3174]: ---------------------------- MPD announces system playlist update Mar 15 21:38:13 primo volumio[3174]: info: Ignoring MPD Status Update Mar 15 21:38:13 primo volumio[3174]: info: Mar 15 21:38:13 primo volumio[3174]: ---------------------------- MPD announces state update: player Mar 15 21:38:13 primo volumio[3174]: info: ControllerMpd::getState Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand status Mar 15 21:38:13 primo volumio[3174]: info: ------------------------------ 17ms Mar 15 21:38:13 primo volumio[3174]: info: sendMpdCommand status took 16 milliseconds Mar 15 21:38:13 primo volumio[3174]: info: ------------------------------ 15ms Mar 15 21:38:13 primo volumio[3174]: info: sendMpdCommand status took 10 milliseconds Mar 15 21:38:13 primo volumio[3174]: info: ------------------------------ 9ms Mar 15 21:38:13 primo volumio[3174]: info: sendMpdCommand status took 7 milliseconds Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::parseState Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::parseState Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::parseState Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 15 21:38:13 primo volumio[3174]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 15 21:38:13 primo volumio[3174]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 15 21:38:13 primo volumio[3174]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::parseTrackInfo Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::parseTrackInfo Mar 15 21:38:13 primo volumio[3174]: verbose: ControllerMpd::parseTrackInfo Mar 15 21:38:13 primo volumio[3174]: info: ControllerMpd::pushState Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::servicePushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":52,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3444 Kbps","isStreaming":false,"title":"281303549","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/281303549","trackType":"qobuz"} Mar 15 21:38:13 primo volumio[3174]: verbose: CURRENT POSITION 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::syncState stateService play Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::syncState currentStatus play Mar 15 21:38:13 primo volumio[3174]: info: Received an update from plugin. extracting info from payload Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::pushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::volumioPushState Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output update for this device Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::pushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::volumioPushState Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output update for this device Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output Mar 15 21:38:13 primo volumio[3174]: info: ControllerMpd::pushState Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::servicePushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":52,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3444 Kbps","isStreaming":false,"title":"281303549","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/281303549","trackType":"qobuz"} Mar 15 21:38:13 primo volumio[3174]: verbose: CURRENT POSITION 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::syncState stateService play Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::syncState currentStatus play Mar 15 21:38:13 primo volumio[3174]: info: Received an update from plugin. extracting info from payload Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::pushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::volumioPushState Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output update for this device Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::pushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::volumioPushState Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output update for this device Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output Mar 15 21:38:13 primo volumio[3174]: info: ControllerMpd::pushState Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::servicePushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":52,"duration":223,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3444 Kbps","isStreaming":false,"title":"281303549","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/281303549","trackType":"qobuz"} Mar 15 21:38:13 primo volumio[3174]: verbose: CURRENT POSITION 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::syncState stateService play Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::syncState currentStatus play Mar 15 21:38:13 primo volumio[3174]: info: Received an update from plugin. extracting info from payload Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::pushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::volumioPushState Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output update for this device Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output Mar 15 21:38:13 primo volumio[3174]: info: CoreStateMachine::pushState Mar 15 21:38:13 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:13 primo volumio[3174]: info: CoreCommandRouter::volumioPushState Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output update for this device Mar 15 21:38:13 primo volumio[3174]: info: MRS: Pushing multiroomSync output Mar 15 21:38:13 primo volumio[3174]: info: ------------------------------ 92ms Mar 15 21:38:13 primo volumio[3174]: info: ------------------------------ 88ms Mar 15 21:38:13 primo volumio[3174]: info: ------------------------------ 84ms Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Signalling Playback active due to playback status change Mar 15 21:38:13 primo volumio[3174]: info: Updating RAAT Signal Path Mar 15 21:38:13 primo volumio[3174]: info: Updating RAAT Signal Path Mar 15 21:38:13 primo volumio[3174]: info: Updating RAAT Signal Path Mar 15 21:38:13 primo volumio[3174]: info: Updating RAAT Signal Path Mar 15 21:38:13 primo volumio[3174]: info: Updating RAAT Signal Path Mar 15 21:38:13 primo volumio[3174]: info: Updating RAAT Signal Path Mar 15 21:38:17 primo volumio[3174]: verbose: New Socket.io Connection to 192.168.68.50 from 192.168.68.51 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:17 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:17 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:17 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetQueue Mar 15 21:38:17 primo volumio[3174]: info: CoreStateMachine::getQueue Mar 15 21:38:17 primo volumio[3174]: info: CorePlayQueue::getQueue Mar 15 21:38:17 primo volumio[3174]: info: Listing playlists Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 15 21:38:17 primo volumio[3174]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 15 21:38:17 primo volumio[3174]: info: Received Get System Info Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 21:38:17 primo volumio[3174]: info: Discovery: Getting this device information Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:17 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:17 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 21:38:17 primo volumio[3174]: info: Discovery: Getting this device information Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:17 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 21:38:19 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:38:19 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:38:19 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:38:19 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:38:19 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:38:19 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:38:20 primo volumio[3174]: STREAMING PROXY: Client dropped request, destroying Mar 15 21:38:47 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 21:38:47 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 21:38:47 primo volumio[3174]: info: Discovery: Getting this device information Mar 15 21:38:47 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:47 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:47 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 21:38:49 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:49 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:51 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:38:51 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:38:51 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:38:51 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:38:51 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:38:51 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 21:38:54 primo volumio[3174]: No protocol specified Mar 15 21:38:54 primo volumio[3174]: xcb_connection_has_error() returned true Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 15 21:38:54 primo volumio[3174]: No protocol specified Mar 15 21:38:54 primo volumio[3174]: xcb_connection_has_error() returned true Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 21:38:54 primo volumio[3174]: info: Received Get System Info Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 21:38:54 primo volumio[3174]: info: Discovery: Getting this device information Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:38:54 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 21:38:54 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 21:39:03 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:39:03 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:39:05 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:39:05 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:39:05 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:39:05 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:39:05 primo volumio[3174]: info: Executing endpoint metavolumio Mar 15 21:39:05 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 15 21:39:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 21:39:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 21:39:17 primo volumio[3174]: info: Discovery: Getting this device information Mar 15 21:39:17 primo volumio[3174]: info: CoreCommandRouter::volumioGetState Mar 15 21:39:17 primo volumio[3174]: info: CorePlayQueue::getTrack 3 Mar 15 21:39:17 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 21:39:24 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Mar 15 21:39:24 primo volumio[3174]: info: Preload queue cleared Mar 15 21:39:24 primo volumio[3174]: info: Preloading song: qobuz://song/11181113 Mar 15 21:39:24 primo volumio[3174]: info: Exploding uri qobuz://song/11181113 in service qobuz Mar 15 21:39:24 primo volumio[3174]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 15 21:39:25 primo volumio[3174]: info: explodeUri took 847 milliseconds Mar 15 21:39:30 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 15 21:39:30 primo volumio[3174]: info: Preload queue cleared Mar 15 21:39:33 primo volumio[3174]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 15 21:39:33 primo volumio[3174]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 21:39:33 primo volumio[3174]: TypeError: Cannot read property 'length' of undefined Mar 15 21:39:33 primo volumio[3174]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 15 21:39:33 primo volumio[3174]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 15 21:39:33 primo volumio[3174]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 15 21:39:33 primo volumio[3174]: at Parser.emit (events.js:400:28) Mar 15 21:39:33 primo volumio[3174]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 15 21:39:33 primo volumio[3174]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 15 21:39:33 primo volumio[3174]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 15 21:39:33 primo volumio[3174]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 15 21:39:33 primo volumio[3174]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 15 21:39:33 primo volumio[3174]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 15 21:39:33 primo volumio[3174]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 15 21:39:33 primo volumio[3174]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 15 21:39:33 primo volumio[3174]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 15 21:39:33 primo volumio[3174]: at IncomingMessage.emit (events.js:412:35) Mar 15 21:39:33 primo volumio[3174]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 15 21:39:33 primo volumio[3174]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 15 21:39:33 primo volumio[3174]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 21:39:34 primo sudo[32246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 21:38 Mar 15 21:39:34 primo sudo[32246]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 01:26:35 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="7bdb40aa125ada75b53200be9c9686c8"