-- Logs begin at Thu 2024-10-24 09:35:24 JST, end at Thu 2024-10-24 09:46:47 JST. -- Oct 24 09:45:06 motivo volumio[919]: error: Failed request for metavolumio API Oct 24 09:45:09 motivo volumio[919]: info: CoreCommandRouter::volumioGetState Oct 24 09:45:11 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:45:11 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:45:11 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:45:11 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:45:11 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:45:11 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:45:11 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:45:11 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:45:12 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:45:12 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:45:13 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 24 09:45:14 motivo volumio[919]: info: handleBrowseUri took 464 milliseconds Oct 24 09:45:14 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:14 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 24 09:45:18 motivo volumio[919]: error: Failed request for metavolumio API Oct 24 09:45:18 motivo volumio[919]: info: handleBrowseUri took 1813 milliseconds Oct 24 09:45:18 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:32 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 24 09:45:32 motivo volumio[919]: info: handleBrowseUri took 200 milliseconds Oct 24 09:45:32 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:32 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:41 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Oct 24 09:45:44 motivo volumio[919]: info: handleBrowseUri took 2927 milliseconds Oct 24 09:45:44 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:58 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::ClearQueue Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::stop Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::stPlaybackTimer Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::updateTrackBlock Oct 24 09:45:58 motivo volumio[919]: info: CorePlayQueue::getTrackBlock Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::pushState Oct 24 09:45:58 motivo volumio[919]: info: CorePlayQueue::getTrack 0 Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::volumioPushState Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::serviceStop Oct 24 09:45:58 motivo volumio[919]: info: CorePlayQueue::getTrack 0 Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::serviceStop Oct 24 09:45:58 motivo volumio[919]: info: [1729730758145] ControllerQobuz::stop Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 09:45:58 motivo volumio[919]: info: ControllerMpd::stop Oct 24 09:45:58 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 09:45:58 motivo volumio[919]: info: CorePlayQueue::clearPlayQueue Oct 24 09:45:58 motivo volumio[919]: info: CorePlayQueue::saveQueue Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::volumioPushQueue Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::addQueueItems Oct 24 09:45:58 motivo volumio[919]: info: CorePlayQueue::addQueueItems Oct 24 09:45:58 motivo volumio[919]: info: Preload queue cleared Oct 24 09:45:58 motivo volumio[919]: info: Adding Item to queue: qobuz://album/d7o3har14nokc Oct 24 09:45:58 motivo volumio[919]: info: Exploding uri qobuz://album/d7o3har14nokc in service qobuz Oct 24 09:45:58 motivo volumio[919]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 24 09:45:58 motivo volumio[919]: STREAMING PROXY: Client dropped request, destroying Oct 24 09:45:58 motivo volumio[919]: info: Updating RAAT Signal Path Oct 24 09:45:58 motivo volumio[919]: info: Oct 24 09:45:58 motivo volumio[919]: ---------------------------- MPD announces state update: player Oct 24 09:45:58 motivo volumio[919]: info: sendMpdCommand stop took 63 milliseconds Oct 24 09:45:58 motivo volumio[919]: info: ControllerMpd::getState Oct 24 09:45:58 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand status Oct 24 09:45:58 motivo volumio[919]: info: sendMpdCommand status took 1 milliseconds Oct 24 09:45:58 motivo volumio[919]: verbose: ControllerMpd::parseState Oct 24 09:45:58 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 09:45:58 motivo volumio[919]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 24 09:45:58 motivo volumio[919]: verbose: ControllerMpd::parseTrackInfo Oct 24 09:45:58 motivo volumio[919]: info: ControllerMpd::pushState Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::servicePushState Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::pushState Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::volumioPushState Oct 24 09:45:58 motivo volumio[919]: info: CorePlayQueue::getTrack 0 Oct 24 09:45:58 motivo volumio[919]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"282697822","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/282697822","trackType":"qobuz"} Oct 24 09:45:58 motivo volumio[919]: verbose: CURRENT POSITION 0 Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::syncState stateService stop Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::syncState currentStatus stop Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::pushState Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::volumioPushState Oct 24 09:45:58 motivo volumio[919]: info: No code Oct 24 09:45:58 motivo volumio[919]: info: CoreStateMachine::pushState Oct 24 09:45:58 motivo volumio[919]: info: CoreCommandRouter::volumioPushState Oct 24 09:45:58 motivo volumio[919]: info: ------------------------------ 27ms Oct 24 09:45:58 motivo volumio[919]: info: Updating RAAT Signal Path Oct 24 09:45:58 motivo volumio[919]: info: Updating RAAT Signal Path Oct 24 09:45:58 motivo volumio[919]: info: Updating RAAT Signal Path Oct 24 09:45:58 motivo volumio[919]: info: Signalling Playback active due to playback status change Oct 24 09:45:58 motivo volumio[919]: info: Signalling Playback active due to playback status change Oct 24 09:45:58 motivo volumio[919]: info: Signalling Playback active due to playback status change Oct 24 09:45:58 motivo volumio[919]: info: MCU Signalled Playback Inactive Oct 24 09:45:58 motivo volumio[919]: info: MCU Signalled Playback Active Oct 24 09:45:59 motivo volumio[919]: info: explodeUri took 1162 milliseconds Oct 24 09:45:59 motivo volumio[919]: info: CoreCommandRouter::volumioPushQueue Oct 24 09:45:59 motivo volumio[919]: info: CorePlayQueue::saveQueue Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::updateTrackBlock Oct 24 09:45:59 motivo volumio[919]: info: CorePlayQueue::getTrackBlock Oct 24 09:45:59 motivo volumio[919]: info: CoreCommandRouter::volumioPlay Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::play index 0 Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::stop Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::play index undefined Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 09:45:59 motivo volumio[919]: info: CorePlayQueue::getTrack 0 Oct 24 09:45:59 motivo volumio[919]: info: CoreStateMachine::startPlaybackTimer Oct 24 09:45:59 motivo volumio[919]: info: CorePlayQueue::getTrack 0 Oct 24 09:45:59 motivo volumio[919]: info: [1729730759329] ControllerQobuz::clearAddPlayTrack Oct 24 09:45:59 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 09:45:59 motivo volumio[919]: info: sendMpdCommand stop took 12 milliseconds Oct 24 09:45:59 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 09:45:59 motivo volumio[919]: info: Oct 24 09:45:59 motivo volumio[919]: ---------------------------- MPD announces system playlist update Oct 24 09:45:59 motivo volumio[919]: info: Ignoring MPD Status Update Oct 24 09:45:59 motivo volumio[919]: info: sendMpdCommand clear took 2 milliseconds Oct 24 09:45:59 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/277462881" Oct 24 09:45:59 motivo volumio[919]: STREAMING PROXY: Handling url /?data=qobuz://song/277462881 Oct 24 09:45:59 motivo volumio[919]: error: updateQueue error: null Oct 24 09:45:59 motivo volumio[919]: info: ------------------------------ 5ms Oct 24 09:45:59 motivo volumio[919]: info: Executing endpoint getStreamUrlqobuz Oct 24 09:45:59 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 24 09:45:59 motivo volumio[919]: info: getStreamUrl took 442 milliseconds Oct 24 09:45:59 motivo volumio[919]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=3319147&eid=277462881&fmt=27&profile=raw&app_id=539451548&cid=2381431&etsp=1729734359&hmac=sH6Nh9Nf6KIMdMecHiCbcOmzQ5g Oct 24 09:46:00 motivo volumio[919]: STREAMING PROXY: Response: 200, length: 120748380 Oct 24 09:46:00 motivo volumio[919]: STREAMING PROXY: Client dropped request, destroying Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/277462881" Oct 24 09:46:00 motivo volumio[919]: info: Oct 24 09:46:00 motivo volumio[919]: ---------------------------- MPD announces system playlist update Oct 24 09:46:00 motivo volumio[919]: info: Ignoring MPD Status Update Oct 24 09:46:00 motivo volumio[919]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/277462881" took 3 milliseconds Oct 24 09:46:00 motivo volumio[919]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand play Oct 24 09:46:00 motivo volumio[919]: STREAMING PROXY: Handling url /?data=qobuz://song/277462881 Oct 24 09:46:00 motivo volumio[919]: info: ------------------------------ 5ms Oct 24 09:46:00 motivo volumio[919]: info: sendMpdCommand play took 4 milliseconds Oct 24 09:46:00 motivo volumio[919]: info: Executing endpoint getStreamUrlqobuz Oct 24 09:46:00 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 24 09:46:00 motivo volumio[919]: info: getStreamUrl took 492 milliseconds Oct 24 09:46:00 motivo volumio[919]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=3319147&eid=277462881&fmt=27&profile=raw&app_id=539451548&cid=2381431&etsp=1729734360&hmac=JQIKcHBxcmiqSpDXZsd6Y0KDits Oct 24 09:46:00 motivo volumio[919]: STREAMING PROXY: Response: 200, length: 120748380 Oct 24 09:46:00 motivo volumio[919]: info: Oct 24 09:46:00 motivo volumio[919]: ---------------------------- MPD announces state update: player Oct 24 09:46:00 motivo volumio[919]: info: ControllerMpd::getState Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand status Oct 24 09:46:00 motivo volumio[919]: info: Oct 24 09:46:00 motivo volumio[919]: ---------------------------- MPD announces state update: player Oct 24 09:46:00 motivo volumio[919]: info: sendMpdCommand status took 22 milliseconds Oct 24 09:46:00 motivo volumio[919]: info: ControllerMpd::getState Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand status Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::parseState Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 09:46:00 motivo volumio[919]: info: sendMpdCommand status took 2 milliseconds Oct 24 09:46:00 motivo volumio[919]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::parseState Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::parseTrackInfo Oct 24 09:46:00 motivo volumio[919]: info: ControllerMpd::pushState Oct 24 09:46:00 motivo volumio[919]: info: CoreCommandRouter::servicePushState Oct 24 09:46:00 motivo volumio[919]: info: CorePlayQueue::getTrack 0 Oct 24 09:46:00 motivo volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"277462881","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/277462881","trackType":"qobuz"} Oct 24 09:46:00 motivo volumio[919]: verbose: CURRENT POSITION 0 Oct 24 09:46:00 motivo volumio[919]: info: CoreStateMachine::syncState stateService play Oct 24 09:46:00 motivo volumio[919]: info: CoreStateMachine::syncState currentStatus stop Oct 24 09:46:00 motivo volumio[919]: info: ------------------------------ 30ms Oct 24 09:46:00 motivo volumio[919]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 24 09:46:00 motivo volumio[919]: verbose: ControllerMpd::parseTrackInfo Oct 24 09:46:00 motivo volumio[919]: info: ControllerMpd::pushState Oct 24 09:46:00 motivo volumio[919]: info: CoreCommandRouter::servicePushState Oct 24 09:46:00 motivo volumio[919]: info: CorePlayQueue::getTrack 0 Oct 24 09:46:00 motivo volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"277462881","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/277462881","trackType":"qobuz"} Oct 24 09:46:00 motivo volumio[919]: verbose: CURRENT POSITION 0 Oct 24 09:46:00 motivo volumio[919]: info: CoreStateMachine::syncState stateService play Oct 24 09:46:00 motivo volumio[919]: info: CoreStateMachine::syncState currentStatus play Oct 24 09:46:00 motivo volumio[919]: info: Received an update from plugin. extracting info from payload Oct 24 09:46:00 motivo volumio[919]: info: CoreStateMachine::pushState Oct 24 09:46:00 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 09:46:00 motivo volumio[919]: info: CoreCommandRouter::volumioPushState Oct 24 09:46:00 motivo volumio[919]: info: CoreStateMachine::pushState Oct 24 09:46:00 motivo volumio[919]: info: CoreCommandRouter::volumioPushState Oct 24 09:46:00 motivo volumio[919]: info: ------------------------------ 45ms Oct 24 09:46:00 motivo volumio[919]: info: Updating RAAT Signal Path Oct 24 09:46:00 motivo volumio[919]: info: Updating RAAT Signal Path Oct 24 09:46:00 motivo volumio[919]: info: Signalling Playback active due to playback status change Oct 24 09:46:00 motivo volumio[919]: info: Signalling Playback active due to playback status change Oct 24 09:46:02 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:46:02 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:46:02 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:46:02 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:46:02 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:46:02 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:46:09 motivo ntpd[823]: 162.159.200.1 local addr 192.168.1.135 -> Oct 24 09:46:09 motivo volumio[919]: error: Failed request for metavolumio API Oct 24 09:46:15 motivo volumio[919]: info: CoreCommandRouter::volumioGetState Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 09:46:16 motivo volumio[919]: info: Retrieving Cloud Streaming UI Oct 24 09:46:16 motivo volumio[919]: info: Getting Tidal Cloud Configuration Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 09:46:16 motivo volumio[919]: info: Getting Qobuz Cloud Configuration Oct 24 09:46:16 motivo volumio[919]: info: Asking plugin for UI Config Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 09:46:16 motivo volumio[919]: info: Getting Spotify Cloud Configuration Oct 24 09:46:16 motivo volumio[919]: info: Asking plugin for UI Config Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 09:46:16 motivo volumio[919]: info: Saving Spotify Acccount Oct 24 09:46:16 motivo volumio[919]: info: Got it Oct 24 09:46:16 motivo volumio[919]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 24 09:46:16 motivo volumio[919]: info: Got Tidal Cloud Configuration Oct 24 09:46:16 motivo volumio[919]: info: Got it Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 09:46:16 motivo volumio[919]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 09:46:17 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:46:17 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:46:17 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:46:17 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:46:17 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:17 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 24 09:46:17 motivo volumio[919]: info: Executing endpoint metavolumio Oct 24 09:46:17 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 09:46:19 motivo volumio[919]: info: Disabling MyMusic plugin bluetooth Oct 24 09:46:19 motivo sudo[2689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Oct 24 09:46:19 motivo sudo[2689]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 09:46:19 motivo systemd[1]: Stopping Volumio Bluetooth Module... Oct 24 09:46:19 motivo volumiobt[2691]: Killing pulse Oct 24 09:46:19 motivo volumio[919]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 09:46:19 motivo volumiobt[2691]: Killing BT Pair agent Oct 24 09:46:19 motivo bluetoothd[800]: Endpoint unregistered: sender=:1.44 path=/MediaEndpoint/A2DPSource Oct 24 09:46:19 motivo bluetoothd[800]: Endpoint unregistered: sender=:1.44 path=/MediaEndpoint/A2DPSink Oct 24 09:46:19 motivo systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Oct 24 09:46:19 motivo systemd[1]: volumiobt.service: Succeeded. Oct 24 09:46:19 motivo systemd[1]: Stopped Volumio Bluetooth Module. Oct 24 09:46:19 motivo sudo[2689]: pam_unix(sudo:session): session closed for user root Oct 24 09:46:19 motivo volumio[919]: info: Volumio BT Module successfully stopped Oct 24 09:46:19 motivo volumio[919]: info: Disabling plugin bluetooth Oct 24 09:46:19 motivo volumio[919]: info: Done. Oct 24 09:46:21 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:22 motivo volumio[919]: info: Disabling MyMusic plugin upnp Oct 24 09:46:22 motivo sudo[2702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 24 09:46:22 motivo sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 09:46:22 motivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 24 09:46:22 motivo volumio[919]: error: Upnp client error: Error: This socket has been ended by the other party Oct 24 09:46:22 motivo volumio[919]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 09:46:25 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:26 motivo volumio[919]: info: Disabling MyMusic plugin airplay_emulation Oct 24 09:46:26 motivo volumio[919]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Oct 24 09:46:26 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 09:46:26 motivo volumio[919]: Cannot find translation for source QOBUZ Oct 24 09:46:26 motivo volumio[919]: Cannot find translation for source TIDAL Oct 24 09:46:26 motivo volumio[919]: info: Disabling plugin airplay_emulation Oct 24 09:46:26 motivo volumio[919]: info: Done. Oct 24 09:46:26 motivo sudo[2713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 24 09:46:26 motivo sudo[2713]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 09:46:26 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 09:46:26 motivo volumio[919]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 09:46:26 motivo systemd[1]: shairport-sync.service: Succeeded. Oct 24 09:46:26 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 09:46:26 motivo sudo[2713]: pam_unix(sudo:session): session closed for user root Oct 24 09:46:26 motivo volumio[919]: info: Shairport-Sync Stopped Oct 24 09:46:29 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:33 motivo volumio[919]: info: Disabling MyMusic plugin tidalconnect Oct 24 09:46:33 motivo vtcs[1533]: [2024-10-24 09:46:33.474] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 24 09:46:33 motivo vtcs[1533]: [2024-10-24 09:46:33.474] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 24 09:46:33 motivo volumio[919]: info: Disabling plugin tidalconnect Oct 24 09:46:33 motivo volumio[919]: info: Done. Oct 24 09:46:33 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:33 motivo sudo[2730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 24 09:46:33 motivo sudo[2730]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 09:46:33 motivo systemd[1]: Stopping Volumio Tidal Connect Service... Oct 24 09:46:33 motivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 24 09:46:33 motivo systemd[1]: vtcs.service: Succeeded. Oct 24 09:46:33 motivo systemd[1]: Stopped Volumio Tidal Connect Service. Oct 24 09:46:33 motivo volumio[919]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 09:46:33 motivo sudo[2730]: pam_unix(sudo:session): session closed for user root Oct 24 09:46:34 motivo volumio[919]: info: Disabling MyMusic plugin smart_inputs Oct 24 09:46:34 motivo volumio[919]: info: Removeing all smart Inputs Oct 24 09:46:34 motivo volumio[919]: info: Disabling plugin smart_inputs Oct 24 09:46:34 motivo volumio[919]: info: Done. Oct 24 09:46:36 motivo volumio[919]: info: TidalConnect service stoped! Oct 24 09:46:37 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:40 motivo volumio[919]: info: Disabling MyMusic plugin metavolumio Oct 24 09:46:40 motivo volumio[919]: info: Removing METAVOLUMIO REST API Endpoints Oct 24 09:46:40 motivo volumio[919]: info: Removing metavolumio REST Endpoint Oct 24 09:46:40 motivo volumio[919]: info: Removing getSimilarArtists REST Endpoint Oct 24 09:46:40 motivo volumio[919]: info: Removing getSimilarAlbums REST Endpoint Oct 24 09:46:40 motivo volumio[919]: info: Removing getSimilarTracks REST Endpoint Oct 24 09:46:40 motivo volumio[919]: info: Disabling plugin metavolumio Oct 24 09:46:40 motivo volumio[919]: info: Done. Oct 24 09:46:41 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:42 motivo volumio[919]: info: Disabling MyMusic plugin upnp Oct 24 09:46:42 motivo sudo[2756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 24 09:46:42 motivo sudo[2756]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 09:46:42 motivo volumio[919]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 09:46:45 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 09:46:47 motivo volumio[919]: info: Enabling MyMusic plugin upnp Oct 24 09:46:47 motivo volumio[919]: info: Enabling plugin upnp Oct 24 09:46:47 motivo volumio[919]: info: Loading plugin "upnp"... Oct 24 09:46:47 motivo volumio[919]: info: [1729730807245] Starting Upmpd Daemon Oct 24 09:46:47 motivo volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 09:46:47 motivo volumio[919]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 09:46:47 motivo volumio[919]: Error: listen EADDRINUSE: address already in use :::6599 Oct 24 09:46:47 motivo volumio[919]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 24 09:46:47 motivo volumio[919]: at listenInCluster (net.js:1366:12) Oct 24 09:46:47 motivo volumio[919]: at Server.listen (net.js:1452:7) Oct 24 09:46:47 motivo volumio[919]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 24 09:46:47 motivo volumio[919]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 24 09:46:47 motivo volumio[919]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 24 09:46:47 motivo volumio[919]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 24 09:46:47 motivo volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 24 09:46:47 motivo volumio[919]: code: 'EADDRINUSE', Oct 24 09:46:47 motivo volumio[919]: errno: -98, Oct 24 09:46:47 motivo volumio[919]: syscall: 'listen', Oct 24 09:46:47 motivo volumio[919]: address: '::', Oct 24 09:46:47 motivo volumio[919]: port: 6599 Oct 24 09:46:47 motivo volumio[919]: } Oct 24 09:46:47 motivo volumio[919]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 09:46:47 motivo sudo[2778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 09:45 Oct 24 09:46:47 motivo sudo[2778]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 07:14:04 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="1b70fb201c03de663c0d0492dcaf203c"