-- Logs begin at Sun 2024-10-27 13:05:35 CET, end at Tue 2024-10-29 09:02:29 CET. -- Oct 29 09:01:01 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:09 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:17 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:20 volumio volumio[1042]: info: Tunnel connection is inactive, restarting it Oct 29 09:01:20 volumio volumio[1042]: info: Starting Tunnel 1 Oct 29 09:01:20 volumio volumio[1042]: info: Starting Tunnel Connection Checker Oct 29 09:01:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:21 volumio sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 29 09:01:21 volumio sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 09:01:21 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 29 09:01:21 volumio autossh[1444]: received signal to exit (15) Oct 29 09:01:21 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 29 09:01:21 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 09:01:21 volumio systemd[1]: sshtunnel.service: Succeeded. Oct 29 09:01:21 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 29 09:01:21 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 29 09:01:21 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 29 09:01:21 volumio sudo[1712]: pam_unix(sudo:session): session closed for user root Oct 29 09:01:21 volumio volumio[1042]: info: Remote SSH Started Oct 29 09:01:21 volumio autossh[1716]: port set to 0, monitoring disabled Oct 29 09:01:21 volumio autossh[1716]: starting ssh (count 1) Oct 29 09:01:21 volumio autossh[1716]: ssh child pid is 1719 Oct 29 09:01:21 volumio volumio[1042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 29 09:01:21 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState Oct 29 09:01:21 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:29 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:31 volumio volumiossh-tunnel[1714]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 29 09:01:33 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:37 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:41 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:41 volumio volumio[1042]: info: MPD Database updated - AlbumList cache refreshed Oct 29 09:01:41 volumio volumio[1042]: info: Oct 29 09:01:41 volumio volumio[1042]: ---------------------------- MPD announces state update: database Oct 29 09:01:41 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Oct 29 09:01:41 volumio volumio[1042]: info: Oct 29 09:01:41 volumio volumio[1042]: ---------------------------- MPD announces state update: update Oct 29 09:01:41 volumio volumio[1042]: info: ControllerMpd::getState Oct 29 09:01:41 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Oct 29 09:01:41 volumio volumio[1042]: info: ControllerMpd::getState Oct 29 09:01:41 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status Oct 29 09:01:44 volumio volumio[1042]: info: sendMpdCommand status took 3341 milliseconds Oct 29 09:01:44 volumio volumio[1042]: info: sendMpdCommand status took 3349 milliseconds Oct 29 09:01:44 volumio volumio[1042]: info: sendMpdCommand status took 3349 milliseconds Oct 29 09:01:44 volumio volumio[1042]: verbose: ControllerMpd::parseState Oct 29 09:01:44 volumio volumio[1042]: info: Command Router : Notfying DB Updatetrue Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent Oct 29 09:01:44 volumio volumio[1042]: verbose: ControllerMpd::parseState Oct 29 09:01:44 volumio volumio[1042]: verbose: ControllerMpd::parseState Oct 29 09:01:44 volumio volumio[1042]: info: ControllerMpd::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 29 09:01:44 volumio volumio[1042]: verbose: CURRENT POSITION 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::syncState stateService stop Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output Oct 29 09:01:44 volumio volumio[1042]: info: No code Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output Oct 29 09:01:44 volumio volumio[1042]: info: ControllerMpd::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 29 09:01:44 volumio volumio[1042]: verbose: CURRENT POSITION 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::syncState stateService stop Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output Oct 29 09:01:44 volumio volumio[1042]: info: No code Oct 29 09:01:44 volumio volumio[1042]: info: CoreStateMachine::pushState Oct 29 09:01:44 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Oct 29 09:01:44 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device Oct 29 09:01:44 volumio volumio[1042]: info: MRS: Pushing multiroomSync output Oct 29 09:01:44 volumio volumio[1042]: info: ------------------------------ 3542ms Oct 29 09:01:44 volumio volumio[1042]: info: ------------------------------ 3542ms Oct 29 09:01:45 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:53 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:01:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:01 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:05 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:09 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:17 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:21 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 29 09:02:25 volumio volumio[1042]: info: Disabling MyMusic plugin upnp Oct 29 09:02:26 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 29 09:02:26 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 09:02:26 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 29 09:02:26 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 29 09:02:26 volumio volumio[1042]: error: Upnp client error: Error: This socket has been ended by the other party Oct 29 09:02:28 volumio volumio[1042]: info: Enabling MyMusic plugin upnp Oct 29 09:02:28 volumio volumio[1042]: info: Enabling plugin upnp Oct 29 09:02:28 volumio volumio[1042]: info: Loading plugin "upnp"... Oct 29 09:02:28 volumio volumio[1042]: info: [1730188948728] Starting Upmpd Daemon Oct 29 09:02:28 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 29 09:02:28 volumio volumio[1042]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 09:02:28 volumio volumio[1042]: Error: listen EADDRINUSE: address already in use :::6599 Oct 29 09:02:28 volumio volumio[1042]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 29 09:02:28 volumio volumio[1042]: at listenInCluster (net.js:1366:12) Oct 29 09:02:28 volumio volumio[1042]: at Server.listen (net.js:1452:7) Oct 29 09:02:28 volumio volumio[1042]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 29 09:02:28 volumio volumio[1042]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 29 09:02:28 volumio volumio[1042]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 29 09:02:28 volumio volumio[1042]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 29 09:02:28 volumio volumio[1042]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 29 09:02:28 volumio volumio[1042]: code: 'EADDRINUSE', Oct 29 09:02:28 volumio volumio[1042]: errno: -98, Oct 29 09:02:28 volumio volumio[1042]: syscall: 'listen', Oct 29 09:02:28 volumio volumio[1042]: address: '::', Oct 29 09:02:28 volumio volumio[1042]: port: 6599 Oct 29 09:02:28 volumio volumio[1042]: } Oct 29 09:02:28 volumio volumio[1042]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 09:02:29 volumio sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-29 09:01 Oct 29 09:02:29 volumio sudo[1787]: 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="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"