-- Logs begin at Tue 2024-05-07 16:59:03 UTC, end at Tue 2024-05-07 17:14:42 UTC. -- May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::volumioNext May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::next May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::stop May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::stPlaybackTimer May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::updateTrackBlock May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrackBlock May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:03 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:03 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::serviceStop May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::serviceStop May 07 17:13:03 bvpi volumio[916]: info: [1715101983857] ControllerWebradio::stop May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:13:03 bvpi volumio[916]: info: May 07 17:13:03 bvpi volumio[916]: ---------------------------- MPD announces state update: player May 07 17:13:03 bvpi volumio[916]: info: sendMpdCommand stop took 14 milliseconds May 07 17:13:03 bvpi volumio[916]: info: ControllerMpd::getState May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::play index undefined May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::startPlaybackTimer May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::volumioGetVisibleSources May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 07 17:13:03 bvpi volumio[916]: info: [1715101983877] ControllerWebradio::clearAddPlayTrack May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::updateTrackBlock May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrackBlock May 07 17:13:03 bvpi volumio[916]: info: sendMpdCommand status took 9 milliseconds May 07 17:13:03 bvpi volumio[916]: info: sendMpdCommand stop took 5 milliseconds May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::parseState May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:13:03 bvpi volumio[916]: info: May 07 17:13:03 bvpi volumio[916]: ---------------------------- MPD announces system playlist update May 07 17:13:03 bvpi volumio[916]: info: Ignoring MPD Status Update May 07 17:13:03 bvpi volumio[916]: info: sendMpdCommand playlistinfo took 2 milliseconds May 07 17:13:03 bvpi volumio[916]: info: sendMpdCommand clear took 2 milliseconds May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::parseTrackInfo May 07 17:13:03 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand load "http://162.244.81.201:5304/stream" May 07 17:13:03 bvpi volumio[916]: info: ControllerMpd::pushState May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::servicePushState May 07 17:13:03 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:03 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:03 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:03 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:03 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:03 bvpi volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 07 17:13:03 bvpi volumio[916]: info: ------------------------------ 31ms May 07 17:13:03 bvpi volumio[916]: error: updateQueue error: null May 07 17:13:03 bvpi volumio[916]: info: ------------------------------ 23ms May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand add "http://162.244.81.201:5304/stream" May 07 17:13:04 bvpi volumio[916]: info: May 07 17:13:04 bvpi volumio[916]: ---------------------------- MPD announces system playlist update May 07 17:13:04 bvpi volumio[916]: info: Ignoring MPD Status Update May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand add "http://162.244.81.201:5304/stream" took 2 milliseconds May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand play May 07 17:13:04 bvpi volumio[916]: info: ------------------------------ 3ms May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand play took 1 milliseconds May 07 17:13:04 bvpi volumio[916]: info: May 07 17:13:04 bvpi volumio[916]: ---------------------------- MPD announces state update: player May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::getState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 07 17:13:04 bvpi volumio[916]: info: May 07 17:13:04 bvpi volumio[916]: ---------------------------- MPD announces state update: player May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand status took 38 milliseconds May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::getState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand status took 2 milliseconds May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand playlistinfo took 1 milliseconds May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseTrackInfo May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::servicePushState May 07 17:13:04 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:04 bvpi volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://162.244.81.201:5304/stream","trackType":""} May 07 17:13:04 bvpi volumio[916]: verbose: CURRENT POSITION 0 May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState stateService play May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 07 17:13:04 bvpi volumio[916]: info: ------------------------------ 49ms May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand playlistinfo took 6 milliseconds May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseTrackInfo May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::servicePushState May 07 17:13:04 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:04 bvpi volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://162.244.81.201:5304/stream","trackType":""} May 07 17:13:04 bvpi volumio[916]: verbose: CURRENT POSITION 0 May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState stateService play May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState currentStatus play May 07 17:13:04 bvpi volumio[916]: info: Received an update from plugin. extracting info from payload May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:04 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:04 bvpi volumio[916]: info: ------------------------------ 39ms May 07 17:13:04 bvpi volumio[916]: info: May 07 17:13:04 bvpi volumio[916]: ---------------------------- MPD announces system playlist update May 07 17:13:04 bvpi volumio[916]: info: Ignoring MPD Status Update May 07 17:13:04 bvpi volumio[916]: info: May 07 17:13:04 bvpi volumio[916]: ---------------------------- MPD announces state update: player May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::getState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 07 17:13:04 bvpi volumio[916]: info: May 07 17:13:04 bvpi volumio[916]: ---------------------------- MPD announces system playlist update May 07 17:13:04 bvpi volumio[916]: info: Ignoring MPD Status Update May 07 17:13:04 bvpi volumio[916]: info: May 07 17:13:04 bvpi volumio[916]: ---------------------------- MPD announces state update: player May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::getState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 07 17:13:04 bvpi volumio[916]: info: ------------------------------ 5ms May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand status took 4 milliseconds May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:13:04 bvpi volumio[916]: info: ------------------------------ 6ms May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand status took 5 milliseconds May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand playlistinfo took 4 milliseconds May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseState May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseTrackInfo May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::servicePushState May 07 17:13:04 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:04 bvpi volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Foo Fighters - Waiting On A War","artist":"jnancejr2","album":null,"uri":"http://162.244.81.201:5304/stream","trackType":""} May 07 17:13:04 bvpi volumio[916]: verbose: CURRENT POSITION 0 May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState stateService play May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState currentStatus play May 07 17:13:04 bvpi volumio[916]: info: Received an update from plugin. extracting info from payload May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:04 bvpi volumio[916]: info: ------------------------------ 35ms May 07 17:13:04 bvpi volumio[916]: info: sendMpdCommand playlistinfo took 26 milliseconds May 07 17:13:04 bvpi volumio[916]: verbose: ControllerMpd::parseTrackInfo May 07 17:13:04 bvpi volumio[916]: info: ControllerMpd::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::servicePushState May 07 17:13:04 bvpi volumio[916]: info: CorePlayQueue::getTrack 0 May 07 17:13:04 bvpi volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Foo Fighters - Waiting On A War","artist":"jnancejr2","album":null,"uri":"http://162.244.81.201:5304/stream","trackType":""} May 07 17:13:04 bvpi volumio[916]: verbose: CURRENT POSITION 0 May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState stateService play May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::syncState currentStatus play May 07 17:13:04 bvpi volumio[916]: info: Received an update from plugin. extracting info from payload May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:04 bvpi volumio[916]: info: CoreStateMachine::pushState May 07 17:13:04 bvpi volumio[916]: info: CoreCommandRouter::volumioPushState May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output update for this device May 07 17:13:04 bvpi volumio[916]: info: MRS: Pushing multiroomSync output May 07 17:13:04 bvpi volumio[916]: info: ------------------------------ 47ms May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:13:20 bvpi volumio[916]: info: Retrieving Cloud Streaming UI May 07 17:13:20 bvpi volumio[916]: info: Getting Tidal Cloud Configuration May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:13:20 bvpi volumio[916]: info: Getting Qobuz Cloud Configuration May 07 17:13:20 bvpi volumio[916]: info: Asking plugin for UI Config May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:13:20 bvpi volumio[916]: info: Getting Spotify Cloud Configuration May 07 17:13:20 bvpi volumio[916]: info: Asking plugin for UI Config May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:13:20 bvpi volumio[916]: info: Saving Spotify Acccount May 07 17:13:20 bvpi volumio[916]: info: Got it May 07 17:13:20 bvpi volumio[916]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 17:13:20 bvpi volumio[916]: info: Got Tidal Cloud Configuration May 07 17:13:20 bvpi volumio[916]: info: Got it May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::volumioGetBrowseSources May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::volumioGetBrowseSources May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::volumioGetBrowseSources May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 07 17:13:24 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:28 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:32 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:36 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:40 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:44 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:48 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:52 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:13:56 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:00 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:04 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:08 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:12 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:16 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:17 bvpi volumio[916]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object] May 07 17:14:17 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions May 07 17:14:20 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:24 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:28 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:32 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:35 bvpi volumio[916]: info: Tunnel connection is inactive, restarting it May 07 17:14:35 bvpi volumio[916]: info: Starting Tunnel 1 May 07 17:14:35 bvpi volumio[916]: info: Starting Tunnel Connection Checker May 07 17:14:35 bvpi sudo[2202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 07 17:14:35 bvpi sudo[2202]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:14:35 bvpi autossh[1997]: received signal to exit (15) May 07 17:14:35 bvpi systemd[1]: Stopping MyVolumio SSH Tunnel... May 07 17:14:35 bvpi systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 07 17:14:35 bvpi systemd[1]: sshtunnel.service: Succeeded. May 07 17:14:35 bvpi systemd[1]: Stopped MyVolumio SSH Tunnel. May 07 17:14:35 bvpi volumio[916]: ------------------------------------ BT MESSAGE: BT STATUS: running May 07 17:14:35 bvpi volumio[916]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 07 17:14:35 bvpi systemd[1]: Started MyVolumio SSH Tunnel. May 07 17:14:35 bvpi sudo[2202]: pam_unix(sudo:session): session closed for user root May 07 17:14:35 bvpi autossh[2206]: port set to 0, monitoring disabled May 07 17:14:35 bvpi autossh[2206]: starting ssh (count 1) May 07 17:14:35 bvpi autossh[2206]: ssh child pid is 2209 May 07 17:14:35 bvpi volumio[916]: info: Remote SSH Started May 07 17:14:35 bvpi volumio[916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 May 07 17:14:35 bvpi volumio[916]: info: CoreCommandRouter::volumioGetState May 07 17:14:36 bvpi volumiossh-tunnel[2204]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. May 07 17:14:36 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:39 bvpi volumio[916]: info: Disabling MyMusic plugin upnp May 07 17:14:39 bvpi sudo[2212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 07 17:14:39 bvpi sudo[2212]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 17:14:39 bvpi systemd[1]: Stopping UPnP Renderer front-end to MPD... May 07 17:14:39 bvpi volumio[916]: error: Upnp client error: Error: This socket has been ended by the other party May 07 17:14:39 bvpi volumio[916]: ------------------------------------ BT MESSAGE: BT STATUS: running May 07 17:14:40 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 17:14:41 bvpi ntpd[700]: 73.193.62.54 local addr 192.168.1.184 -> May 07 17:14:41 bvpi volumio[916]: info: Enabling MyMusic plugin upnp May 07 17:14:41 bvpi volumio[916]: info: Enabling plugin upnp May 07 17:14:41 bvpi volumio[916]: info: Loading plugin "upnp"... May 07 17:14:41 bvpi volumio[916]: info: [1715102081980] Starting Upmpd Daemon May 07 17:14:41 bvpi volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 17:14:41 bvpi volumio[916]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 17:14:41 bvpi volumio[916]: Error: listen EADDRINUSE: address already in use :::6599 May 07 17:14:41 bvpi volumio[916]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 07 17:14:41 bvpi volumio[916]: at listenInCluster (net.js:1366:12) May 07 17:14:41 bvpi volumio[916]: at Server.listen (net.js:1452:7) May 07 17:14:41 bvpi volumio[916]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 07 17:14:41 bvpi volumio[916]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 07 17:14:41 bvpi volumio[916]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 07 17:14:41 bvpi volumio[916]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 07 17:14:41 bvpi volumio[916]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 07 17:14:41 bvpi volumio[916]: code: 'EADDRINUSE', May 07 17:14:41 bvpi volumio[916]: errno: -98, May 07 17:14:41 bvpi volumio[916]: syscall: 'listen', May 07 17:14:41 bvpi volumio[916]: address: '::', May 07 17:14:41 bvpi volumio[916]: port: 6599 May 07 17:14:41 bvpi volumio[916]: } May 07 17:14:41 bvpi volumio[916]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 17:14:42 bvpi sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-07 17:13 May 07 17:14:42 bvpi sudo[2223]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"