-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-11-26 16:04:56 CET. -- Nov 26 16:03:00 volumio-bridge volumio[798]: error: Upnp client error: Error: This socket has been ended by the other party Nov 26 16:03:04 volumio-bridge volumio[798]: info: Starting UPNP Playback Nov 26 16:03:04 volumio-bridge volumio[798]: info: Preparing playback through UPNP Nov 26 16:03:04 volumio-bridge volumio[798]: info: Consume mode Nov 26 16:03:04 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:03:04 volumio-bridge volumio[798]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 26 16:03:04 volumio-bridge volumio[798]: info: Clearing queue after UPNP request Nov 26 16:03:05 volumio-bridge volumio[798]: info: CoreStateMachine::ClearQueue Nov 26 16:03:05 volumio-bridge volumio[798]: info: CoreStateMachine::stop Nov 26 16:03:05 volumio-bridge volumio[798]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 16:03:05 volumio-bridge volumio[798]: info: CorePlayQueue::clearPlayQueue Nov 26 16:03:05 volumio-bridge volumio[798]: info: CorePlayQueue::saveQueue Nov 26 16:03:05 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioPushState Nov 26 16:03:05 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioPushQueue Nov 26 16:03:05 volumio-bridge volumio[798]: info: Nov 26 16:03:05 volumio-bridge volumio[798]: ---------------------------- MPD announces system playlist update Nov 26 16:03:05 volumio-bridge volumio[798]: info: Ignoring MPD Status Update Nov 26 16:03:05 volumio-bridge volumio[798]: error: updateQueue error: null Nov 26 16:03:05 volumio-bridge volumio[798]: info: ------------------------------ 4ms Nov 26 16:03:05 volumio-bridge volumio[798]: info: Starting UPNP Playback Nov 26 16:03:05 volumio-bridge volumio[798]: info: Preparing playback through UPNP Nov 26 16:03:05 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:03:05 volumio-bridge volumio[798]: info: CorePlayQueue::getTrack 0 Nov 26 16:03:05 volumio-bridge volumio[798]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 26 16:03:25 volumio-bridge volumiologrotate[532]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 26 16:03:25 volumio-bridge volumiologrotate[532]: ls: cannot access 'BRIDGE': No such file or directory Nov 26 16:04:13 volumio-bridge volumio[798]: verbose: New Socket.io Connection to 192.168.50.195 from 192.168.50.181 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetQueue Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreStateMachine::getQueue Nov 26 16:04:13 volumio-bridge volumio[798]: info: CorePlayQueue::getQueue Nov 26 16:04:13 volumio-bridge volumio[798]: info: Listing playlists Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 16:04:13 volumio-bridge volumio[798]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 26 16:04:13 volumio-bridge volumio[798]: info: Received Get System Info Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 16:04:13 volumio-bridge volumio[798]: info: Discovery: Getting this device information Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 16:04:13 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 26 16:04:14 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 26 16:04:15 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 16:04:15 volumio-bridge volumio[798]: info: Received Get System Info Nov 26 16:04:15 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 16:04:15 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 16:04:15 volumio-bridge volumio[798]: info: Discovery: Getting this device information Nov 26 16:04:15 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:15 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 16:04:16 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 16:04:16 volumio-bridge volumio[798]: info: Received Get System Info Nov 26 16:04:16 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 16:04:16 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 16:04:16 volumio-bridge volumio[798]: info: Discovery: Getting this device information Nov 26 16:04:16 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:16 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 16:04:23 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 26 16:04:23 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:25 volumio-bridge volumio[798]: info: Executing endpoint metavolumio Nov 26 16:04:25 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 26 16:04:25 volumio-bridge volumiologrotate[532]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 26 16:04:25 volumio-bridge volumiologrotate[532]: ls: cannot access 'BRIDGE': No such file or directory Nov 26 16:04:31 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 16:04:31 volumio-bridge volumio[798]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 26 16:04:31 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 26 16:04:31 volumio-bridge volumio[798]: info: Received Get System Version Nov 26 16:04:31 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 16:04:31 volumio-bridge volumio[798]: info: Received Get System Info Nov 26 16:04:31 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 16:04:31 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 16:04:31 volumio-bridge volumio[798]: info: Discovery: Getting this device information Nov 26 16:04:31 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:31 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 16:04:32 volumio-bridge volumio[798]: info: Retrieving Cloud Streaming UI Nov 26 16:04:32 volumio-bridge volumio[798]: info: Getting Tidal Cloud Configuration Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 16:04:32 volumio-bridge volumio[798]: info: Getting Qobuz Cloud Configuration Nov 26 16:04:32 volumio-bridge volumio[798]: info: Asking plugin for UI Config Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 16:04:32 volumio-bridge volumio[798]: info: Getting Spotify Cloud Configuration Nov 26 16:04:32 volumio-bridge volumio[798]: info: Asking plugin for UI Config Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 16:04:32 volumio-bridge volumio[798]: info: Saving Spotify Acccount Nov 26 16:04:32 volumio-bridge volumio[798]: info: Got it Nov 26 16:04:32 volumio-bridge volumio[798]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 26 16:04:32 volumio-bridge volumio[798]: info: Got Tidal Cloud Configuration Nov 26 16:04:32 volumio-bridge volumio[798]: info: Got it Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 16:04:32 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 26 16:04:35 volumio-bridge volumio[798]: info: Disabling MyMusic plugin upnp Nov 26 16:04:35 volumio-bridge sudo[6359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 26 16:04:35 volumio-bridge sudo[6359]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 16:04:35 volumio-bridge volumio[798]: error: Upnp client error: Error: This socket has been ended by the other party Nov 26 16:04:35 volumio-bridge systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 26 16:04:36 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 16:04:40 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 16:04:44 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 16:04:48 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 16:04:52 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 16:04:53 volumio-bridge volumio[798]: info: Tunnel connection is inactive, restarting it Nov 26 16:04:53 volumio-bridge volumio[798]: info: Starting Tunnel 1 Nov 26 16:04:53 volumio-bridge volumio[798]: info: Starting Tunnel Connection Checker Nov 26 16:04:53 volumio-bridge sudo[6384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 26 16:04:53 volumio-bridge sudo[6384]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 16:04:53 volumio-bridge autossh[5874]: received signal to exit (15) Nov 26 16:04:53 volumio-bridge systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 26 16:04:53 volumio-bridge systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 26 16:04:53 volumio-bridge systemd[1]: sshtunnel.service: Succeeded. Nov 26 16:04:53 volumio-bridge systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 26 16:04:53 volumio-bridge systemd[1]: Started MyVolumio SSH Tunnel. Nov 26 16:04:53 volumio-bridge sudo[6384]: pam_unix(sudo:session): session closed for user root Nov 26 16:04:53 volumio-bridge autossh[6400]: port set to 0, monitoring disabled Nov 26 16:04:53 volumio-bridge autossh[6400]: starting ssh (count 1) Nov 26 16:04:53 volumio-bridge autossh[6400]: ssh child pid is 6404 Nov 26 16:04:53 volumio-bridge volumio[798]: info: Remote SSH Started Nov 26 16:04:53 volumio-bridge volumio[798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 26 16:04:53 volumio-bridge volumio[798]: info: CoreCommandRouter::volumioGetState Nov 26 16:04:56 volumio-bridge volumio[798]: info: Enabling MyMusic plugin upnp Nov 26 16:04:56 volumio-bridge volumio[798]: info: Enabling plugin upnp Nov 26 16:04:56 volumio-bridge volumio[798]: info: Loading plugin "upnp"... Nov 26 16:04:56 volumio-bridge volumio[798]: info: [1732633496182] Starting Upmpd Daemon Nov 26 16:04:56 volumio-bridge volumio[798]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 16:04:56 volumio-bridge volumio[798]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 16:04:56 volumio-bridge volumio[798]: Error: listen EADDRINUSE: address already in use :::6599 Nov 26 16:04:56 volumio-bridge volumio[798]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 26 16:04:56 volumio-bridge volumio[798]: at listenInCluster (net.js:1366:12) Nov 26 16:04:56 volumio-bridge volumio[798]: at Server.listen (net.js:1452:7) Nov 26 16:04:56 volumio-bridge volumio[798]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 26 16:04:56 volumio-bridge volumio[798]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 26 16:04:56 volumio-bridge volumio[798]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 26 16:04:56 volumio-bridge volumio[798]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 16:04:56 volumio-bridge volumio[798]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 26 16:04:56 volumio-bridge volumio[798]: code: 'EADDRINUSE', Nov 26 16:04:56 volumio-bridge volumio[798]: errno: -98, Nov 26 16:04:56 volumio-bridge volumio[798]: syscall: 'listen', Nov 26 16:04:56 volumio-bridge volumio[798]: address: '::', Nov 26 16:04:56 volumio-bridge volumio[798]: port: 6599 Nov 26 16:04:56 volumio-bridge volumio[798]: } Nov 26 16:04:56 volumio-bridge volumio[798]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 16:04:56 volumio-bridge sudo[6417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 16:03 Nov 26 16:04:56 volumio-bridge sudo[6417]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"