-- Logs begin at Sat 2024-03-30 23:38:16 +03, end at Wed 2024-04-03 13:56:24 +03. -- Apr 03 13:55:01 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:05 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:09 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:13 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:17 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:21 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:25 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:26 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 03 13:55:26 danjohn volumio[740]: verbose: ControllerMpd::sendMpdCommand rescan Apr 03 13:55:27 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 03 13:55:27 danjohn volumio[740]: verbose: ControllerMpd::sendMpdCommand update Apr 03 13:55:29 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:33 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:37 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:40 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Apr 03 13:55:40 danjohn sudo[30378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/AHTE Apr 03 13:55:40 danjohn sudo[30378]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:55:40 danjohn systemd[1]: mnt-NAS-AHTE.mount: Succeeded. Apr 03 13:55:40 danjohn sudo[30378]: pam_unix(sudo:session): session closed for user root Apr 03 13:55:40 danjohn volumio[740]: info: Share AHTE successfully unmounted Apr 03 13:55:40 danjohn sudo[30382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=danjohnmusic,password=XT-NAZMI@MUSIC,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //miniso.synology.me/new brands music/dan john /mnt/NAS/AHTE Apr 03 13:55:40 danjohn sudo[30382]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:55:40 danjohn kernel: CIFS: Attempting to mount \\miniso.synology.me\new brands music Apr 03 13:55:41 danjohn sudo[30382]: pam_unix(sudo:session): session closed for user root Apr 03 13:55:41 danjohn volumio[740]: info: [1712141741716] Database update started Apr 03 13:55:41 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:42 danjohn volumio[740]: info: Disabling MyMusic plugin upnp_browser Apr 03 13:55:42 danjohn volumio[740]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Apr 03 13:55:42 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:55:42 danjohn volumio[740]: info: Disabling plugin upnp_browser Apr 03 13:55:42 danjohn volumio[740]: info: Done. Apr 03 13:55:42 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 03 13:55:43 danjohn volumio[740]: info: Enabling MyMusic plugin upnp_browser Apr 03 13:55:43 danjohn volumio[740]: info: Enabling plugin upnp_browser Apr 03 13:55:43 danjohn volumio[740]: info: Loading plugin "upnp_browser"... Apr 03 13:55:43 danjohn volumio[740]: info: PLUGIN START: upnp_browser Apr 03 13:55:43 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:55:43 danjohn volumio[740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 13:55:43 danjohn volumio[740]: info: [1712141743197] CoreMusicLibrary::Adding element Media Servers Apr 03 13:55:43 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:55:43 danjohn volumio[740]: info: Done. Apr 03 13:55:44 danjohn volumio[740]: info: Enabling MyMusic plugin bluetooth Apr 03 13:55:44 danjohn volumio[740]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Apr 03 13:55:44 danjohn volumio[740]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Apr 03 13:55:44 danjohn volumio[740]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Apr 03 13:55:44 danjohn volumio[740]: error: Could not Enable MyMusic Plugin: Error Apr 03 13:55:45 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:46 danjohn volumio[740]: info: Enabling MyMusic plugin multiroom Apr 03 13:55:46 danjohn volumio[740]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Apr 03 13:55:46 danjohn volumio[740]: info: [MyVolumio PluginManager] Plugin audio_interface multiroom not in user plan, not enabling Apr 03 13:55:46 danjohn volumio[740]: info: Error: Plugin audio_interface multiroom not in user plan, not enabling Apr 03 13:55:46 danjohn volumio[740]: error: Could not Enable MyMusic Plugin: Error Apr 03 13:55:48 danjohn sudo[30395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 13:55:48 danjohn sudo[30395]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:55:48 danjohn sudo[30395]: pam_unix(sudo:session): session closed for user root Apr 03 13:55:48 danjohn sudo[30398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 13:55:48 danjohn sudo[30398]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:55:48 danjohn sudo[30398]: pam_unix(sudo:session): session closed for user root Apr 03 13:55:48 danjohn volumio[740]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 5 Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:48 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:48 danjohn volumio[740]: info: Received Get System Info Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:55:48 danjohn volumio[740]: info: Discovery: Getting this device information Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:48 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:48 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:48 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:48 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 03 13:55:48 danjohn volumio[740]: info: Received Get System Info Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:55:48 danjohn volumio[740]: info: Discovery: Getting this device information Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:48 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:48 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:48 danjohn volumio[740]: info: Listing playlists Apr 03 13:55:48 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 03 13:55:50 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 03 13:55:50 danjohn volumio[740]: info: Received Get System Info Apr 03 13:55:50 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:55:50 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:55:50 danjohn volumio[740]: info: Discovery: Getting this device information Apr 03 13:55:50 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:50 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:50 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:55:52 danjohn sudo[30401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 13:55:52 danjohn sudo[30401]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:55:52 danjohn sudo[30401]: pam_unix(sudo:session): session closed for user root Apr 03 13:55:52 danjohn sudo[30404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 13:55:52 danjohn sudo[30404]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:55:52 danjohn sudo[30404]: pam_unix(sudo:session): session closed for user root Apr 03 13:55:52 danjohn volumio[740]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 5 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: Received Get System Info Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:55:52 danjohn volumio[740]: info: Discovery: Getting this device information Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 03 13:55:52 danjohn volumio[740]: info: Received Get System Info Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:55:52 danjohn volumio[740]: info: Discovery: Getting this device information Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: Listing playlists Apr 03 13:55:52 danjohn volumio[740]: info: Received Get System Info Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:55:52 danjohn volumio[740]: info: Discovery: Getting this device information Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:52 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:52 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 03 13:55:53 danjohn volumio[740]: info: CoreCommandRouter::volumioGetState Apr 03 13:55:53 danjohn volumio[740]: info: CorePlayQueue::getTrack 16 Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 13:55:56 danjohn volumio[740]: info: Retrieving Cloud Streaming UI Apr 03 13:55:56 danjohn volumio[740]: info: Getting Tidal Cloud Configuration Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 13:55:56 danjohn volumio[740]: info: Getting Qobuz Cloud Configuration Apr 03 13:55:56 danjohn volumio[740]: info: Asking plugin for UI Config Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 13:55:56 danjohn volumio[740]: info: Getting Spotify Cloud Configuration Apr 03 13:55:56 danjohn volumio[740]: info: Asking plugin for UI Config Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 13:55:56 danjohn volumio[740]: info: Saving Spotify Acccount Apr 03 13:55:56 danjohn volumio[740]: info: Got Tidal Cloud Configuration Apr 03 13:55:56 danjohn volumio[740]: info: Got it Apr 03 13:55:56 danjohn volumio[740]: info: Got it Apr 03 13:55:56 danjohn volumio[740]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 13:55:56 danjohn volumio[740]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:55:56 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 03 13:56:00 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:56:01 danjohn volumio[740]: info: Enabling MyMusic plugin tidalconnect Apr 03 13:56:01 danjohn volumio[740]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect Apr 03 13:56:01 danjohn volumio[740]: info: [MyVolumio PluginManager] Plugin music_service tidalconnect not in user plan, not enabling Apr 03 13:56:01 danjohn volumio[740]: info: Error: Plugin music_service tidalconnect not in user plan, not enabling Apr 03 13:56:01 danjohn volumio[740]: error: Could not Enable MyMusic Plugin: Error Apr 03 13:56:04 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:56:05 danjohn volumio[740]: info: Disabling MyMusic plugin upnp Apr 03 13:56:05 danjohn sudo[30410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 03 13:56:05 danjohn sudo[30410]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:05 danjohn systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 03 13:56:05 danjohn volumio[740]: error: Upnp client error: Error: This socket has been ended by the other party Apr 03 13:56:06 danjohn volumio[740]: info: Enabling MyMusic plugin upnp Apr 03 13:56:06 danjohn volumio[740]: info: Enabling plugin upnp Apr 03 13:56:06 danjohn volumio[740]: info: Loading plugin "upnp"... Apr 03 13:56:06 danjohn volumio[740]: info: [1712141766321] Starting Upmpd Daemon Apr 03 13:56:06 danjohn volumio[740]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:56:06 danjohn volumio[740]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:56:06 danjohn volumio[740]: Error: listen EADDRINUSE: address already in use :::6599 Apr 03 13:56:06 danjohn volumio[740]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Apr 03 13:56:06 danjohn volumio[740]: at listenInCluster (net.js:1366:12) Apr 03 13:56:06 danjohn volumio[740]: at Server.listen (net.js:1452:7) Apr 03 13:56:06 danjohn volumio[740]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 03 13:56:06 danjohn volumio[740]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 03 13:56:06 danjohn volumio[740]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 03 13:56:06 danjohn volumio[740]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 03 13:56:06 danjohn volumio[740]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 03 13:56:06 danjohn volumio[740]: code: 'EADDRINUSE', Apr 03 13:56:06 danjohn volumio[740]: errno: -98, Apr 03 13:56:06 danjohn volumio[740]: syscall: 'listen', Apr 03 13:56:06 danjohn volumio[740]: address: '::', Apr 03 13:56:06 danjohn volumio[740]: port: 6599 Apr 03 13:56:06 danjohn volumio[740]: } Apr 03 13:56:06 danjohn volumio[740]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:56:06 danjohn sudo[30421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-03 13:55 Apr 03 13:56:06 danjohn sudo[30421]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:06 danjohn sudo[30421]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:07 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 03 13:56:07 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 03 13:56:07 danjohn systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 03 13:56:07 danjohn systemd[1]: upmpdcli.service: Succeeded. Apr 03 13:56:07 danjohn systemd[1]: Stopped UPnP Renderer front-end to MPD. Apr 03 13:56:07 danjohn sudo[30410]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:07 danjohn systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 03 13:56:07 danjohn systemd[1]: Started dynamicswap service. Apr 03 13:56:07 danjohn systemd[1]: dynamicswap.service: Succeeded. Apr 03 13:56:07 danjohn systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 03 13:56:07 danjohn systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 03 13:56:07 danjohn systemd[1]: Started dynamicswap service. Apr 03 13:56:07 danjohn systemd[1]: Stopped Volumio Backend Module. Apr 03 13:56:07 danjohn systemd[1]: Started Volumio Backend Module. Apr 03 13:56:07 danjohn systemd[1]: dynamicswap.service: Succeeded. Apr 03 13:56:08 danjohn volumio[30443]: info: ------------------------------------------- Apr 03 13:56:08 danjohn volumio[30443]: info: ----- Volumio3 ---- Apr 03 13:56:08 danjohn volumio[30443]: info: ------------------------------------------- Apr 03 13:56:08 danjohn volumio[30443]: info: ----- System startup ---- Apr 03 13:56:08 danjohn volumio[30443]: info: ------------------------------------------- Apr 03 13:56:09 danjohn volumio[30443]: info: MYVOLUMIO Environment detected Apr 03 13:56:09 danjohn volumio[30443]: info: Plugin folders cleanup Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning into folder /volumio/app/plugins/ Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning category audio_interface Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning category miscellanea Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning category music_service Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning category plugins.json Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning category system_controller Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning category user_interface Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning into folder /data/plugins/ Apr 03 13:56:09 danjohn volumio[30443]: info: Scanning category system_controller Apr 03 13:56:09 danjohn volumio[30443]: info: Plugin folders cleanup completed Apr 03 13:56:09 danjohn volumio[30443]: info: ------------------------------------------- Apr 03 13:56:09 danjohn volumio[30443]: info: ----- Core plugins startup ---- Apr 03 13:56:09 danjohn volumio[30443]: info: ------------------------------------------- Apr 03 13:56:09 danjohn volumio[30443]: info: Loading plugins from folder /volumio/app/plugins/ Apr 03 13:56:09 danjohn volumio[30443]: info: Adding plugin upnp to MyMusic Plugins Apr 03 13:56:09 danjohn volumio[30443]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 03 13:56:09 danjohn volumio[30443]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 03 13:56:09 danjohn volumio[30443]: info: Loading plugins from folder /data/plugins/ Apr 03 13:56:09 danjohn volumio[30443]: info: Loading plugin "system"... Apr 03 13:56:09 danjohn volumio[30443]: info: Loading plugin "appearance"... Apr 03 13:56:09 danjohn volumio[30443]: info: Loading plugin "network"... Apr 03 13:56:09 danjohn volumio[30443]: info: Refreshing Cached IP Addresses Apr 03 13:56:09 danjohn sudo[30470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 13:56:09 danjohn sudo[30470]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:09 danjohn sudo[30470]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:09 danjohn sudo[30472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 13:56:09 danjohn sudo[30472]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:09 danjohn volumio[30443]: info: Loading plugin "services"... Apr 03 13:56:09 danjohn sudo[30472]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:09 danjohn volumio[30443]: info: Loading plugin "alsa_controller"... Apr 03 13:56:09 danjohn sudo[30475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 03 13:56:09 danjohn sudo[30475]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:10 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "wizard"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "networkfs"... Apr 03 13:56:10 danjohn volumio[30443]: info: Cannot mount NAS AHTE at system boot, trial number 1 ,retrying in 5 seconds Apr 03 13:56:10 danjohn volumio[30443]: info: Starting Udev Watcher for removable devices Apr 03 13:56:10 danjohn volumio[30443]: info: Ignoring mount for partition: boot Apr 03 13:56:10 danjohn volumio[30443]: info: Ignoring mount for partition: volumio Apr 03 13:56:10 danjohn volumio[30443]: info: Ignoring mount for partition: volumio_data Apr 03 13:56:10 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "volumio_command_line_client"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "upnp"... Apr 03 13:56:10 danjohn volumio[30443]: info: [1712141770097] Starting Upmpd Daemon Apr 03 13:56:10 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "my_music"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "mpd"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "upnp_browser"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "alarm-clock"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "airplay_emulation"... Apr 03 13:56:10 danjohn volumio[30443]: info: Starting Shairport Sync Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "last_100"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "webradio"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "i2s_dacs"... Apr 03 13:56:10 danjohn volumio[30443]: info: I2S DAC not set, start Auto-detection Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "volumiodiscovery"... Apr 03 13:56:10 danjohn volumio[30443]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:10 danjohn volumio[30443]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:10 danjohn node[30443]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:10 danjohn volumio[30443]: *** WARNING *** For more information see Apr 03 13:56:10 danjohn volumio[30443]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:10 danjohn volumio[30443]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:10 danjohn volumio[30443]: *** WARNING *** For more information see Apr 03 13:56:10 danjohn node[30443]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:10 danjohn node[30443]: *** WARNING *** For more information see Apr 03 13:56:10 danjohn node[30443]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:10 danjohn node[30443]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:10 danjohn node[30443]: *** WARNING *** For more information see Apr 03 13:56:10 danjohn volumio[30443]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 03 13:56:10 danjohn volumio[30443]: info: Discovery: Started advertising with name: danjohn Apr 03 13:56:10 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "autostart"... Apr 03 13:56:10 danjohn volumio[30443]: info: Applying required configuration parameters for plugin autostart Apr 03 13:56:10 danjohn volumio[30443]: info: AutoStart - onVolumioStart - read config.json Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "outputs"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "albumart"... Apr 03 13:56:10 danjohn volumio[30443]: info: Plugin example_plugin is not enabled Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "inputs"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "updater_comm"... Apr 03 13:56:10 danjohn volumio[30443]: info: Plugin mpdemulation is not enabled Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "rest_api"... Apr 03 13:56:10 danjohn volumio[30443]: info: Loading plugin "websocket"... Apr 03 13:56:11 danjohn volumio[30443]: info: Loading i18n strings for locale en Apr 03 13:56:11 danjohn volumio[30443]: Updating browse sources language Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:56:11 danjohn volumio[30443]: Forking 3 albumart workers Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::initPlayerControls Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: Express server listening on port 3000 Apr 03 13:56:11 danjohn volumio[30443]: [Metrics] WebUI: 2s 593.59ms Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::resetVolumioState Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::getcurrentVolume Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:56:11 danjohn volumio[30443]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 1 Apr 03 13:56:11 danjohn volumio[30443]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 2 Apr 03 13:56:11 danjohn volumio[30443]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3 Apr 03 13:56:11 danjohn volumio[30443]: info: Reloading queue from file Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::setRepeat false single undefined Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::pushState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::setRandom false Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::pushState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:11 danjohn volumio[30443]: info: Setting Device type: Raspberry PI Apr 03 13:56:11 danjohn volumio[30443]: info: VolumeController:: Volume=62 Mute =false Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::pushState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::updateTrackBlock Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrackBlock Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 13:56:11 danjohn volumio[30443]: info: VolumeController:: Volume=62 Mute =false Apr 03 13:56:11 danjohn volumio[30443]: info: CoreStateMachine::pushState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:11 danjohn volumio[30443]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: Received Get System Info Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: Getting this device information Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: Received Get System Info Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: Getting this device information Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 03 13:56:11 danjohn volumio[30443]: Starting albumart workers Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 03 13:56:11 danjohn volumio[30443]: info: Received Get System Info Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: Getting this device information Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: Starting albumart workers Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: Listing playlists Apr 03 13:56:11 danjohn volumio[30443]: Starting albumart workers Apr 03 13:56:11 danjohn volumio[30443]: info: Received Get System Info Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: Getting this device information Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: adding 2ec3c2bd-f66e-4a2d-a89c-cfac2168356e Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: Found device danjohn Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: this is already registered, 2ec3c2bd-f66e-4a2d-a89c-cfac2168356e Apr 03 13:56:11 danjohn volumio[30443]: info: Discovery: Found device danjohn Apr 03 13:56:11 danjohn volumio[30443]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:11 danjohn volumio[30443]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:12 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:56:12 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:12] [connect] Successful connection Apr 03 13:56:12 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712141772 101 Apr 03 13:56:12 danjohn volumio[30443]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Apr 03 13:56:12 danjohn volumio[30443]: info: Enabling MyMusic plugin airplay_emulation Apr 03 13:56:12 danjohn volumio[30443]: info: Enabling plugin airplay_emulation Apr 03 13:56:12 danjohn volumio[30443]: info: Loading plugin "airplay_emulation"... Apr 03 13:56:12 danjohn volumio[30443]: info: Starting Shairport Sync Apr 03 13:56:12 danjohn volumio[30443]: info: PLUGIN START: airplay_emulation Apr 03 13:56:12 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:12 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:56:12 danjohn volumio[30443]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 13:56:12 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:12 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:56:12 danjohn volumio[30443]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 13:56:12 danjohn volumio[30443]: info: Done. Apr 03 13:56:12 danjohn volumio[30443]: info: Starting Shairport Sync Apr 03 13:56:12 danjohn volumio[30443]: info: Starting Shairport Sync Apr 03 13:56:12 danjohn sudo[30556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 13:56:12 danjohn sudo[30556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:12 danjohn sudo[30558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 13:56:12 danjohn sudo[30558]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:12 danjohn systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 13:56:12 danjohn systemd[1]: shairport-sync.service: Succeeded. Apr 03 13:56:12 danjohn systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 13:56:12 danjohn systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 13:56:12 danjohn systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 13:56:12 danjohn systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 03 13:56:12 danjohn systemd[1]: shairport-sync.service: Succeeded. Apr 03 13:56:12 danjohn sudo[30556]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:12 danjohn systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 13:56:12 danjohn volumio[30443]: info: Shairport-Sync Started Apr 03 13:56:12 danjohn systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 13:56:12 danjohn sudo[30558]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:12 danjohn volumio[30443]: Error adding Membership: Error: addMembership EINVAL Apr 03 13:56:12 danjohn volumio[30443]: info: Shairport-Sync Started Apr 03 13:56:13 danjohn sudo[30475]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:15 danjohn volumio[30443]: info: Cannot mount NAS AHTE at system boot, trial number 2 ,retrying in 5 seconds Apr 03 13:56:15 danjohn volumio[30443]: info: Disabling MyMusic plugin upnp_browser Apr 03 13:56:15 danjohn volumio[30443]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Apr 03 13:56:15 danjohn volumio[30443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:56:15 danjohn volumio[30443]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:56:15 danjohn volumio[30443]: TypeError: Cannot read property 'stop' of undefined Apr 03 13:56:15 danjohn volumio[30443]: at ControllerUPNPBrowser.onStop (/volumio/app/plugins/music_service/upnp_browser/index.js:147:10) Apr 03 13:56:15 danjohn volumio[30443]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 03 13:56:15 danjohn volumio[30443]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 03 13:56:15 danjohn volumio[30443]: at PluginManager.enableDisableMyMusicPlugin (/volumio/app/pluginmanager.js:2048:26) Apr 03 13:56:15 danjohn volumio[30443]: at CoreCommandRouter.enableDisableMyMusicPlugin (/volumio/app/index.js:2077:29) Apr 03 13:56:15 danjohn volumio[30443]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1866:59) Apr 03 13:56:15 danjohn volumio[30443]: at Socket.emit (events.js:315:20) Apr 03 13:56:15 danjohn volumio[30443]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 03 13:56:15 danjohn volumio[30443]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 03 13:56:15 danjohn volumio[30443]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:56:15 danjohn sudo[30577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-03 13:55 Apr 03 13:56:15 danjohn sudo[30577]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:15 danjohn sudo[30577]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:16 danjohn systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 03 13:56:16 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 03 13:56:16 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 03 13:56:16 danjohn systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 03 13:56:16 danjohn systemd[1]: Started dynamicswap service. Apr 03 13:56:16 danjohn systemd[1]: dynamicswap.service: Succeeded. Apr 03 13:56:16 danjohn systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 03 13:56:16 danjohn systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Apr 03 13:56:16 danjohn systemd[1]: Started dynamicswap service. Apr 03 13:56:16 danjohn systemd[1]: Stopped Volumio Backend Module. Apr 03 13:56:16 danjohn systemd[1]: Started Volumio Backend Module. Apr 03 13:56:16 danjohn systemd[1]: dynamicswap.service: Succeeded. Apr 03 13:56:17 danjohn volumio[30592]: info: ------------------------------------------- Apr 03 13:56:17 danjohn volumio[30592]: info: ----- Volumio3 ---- Apr 03 13:56:17 danjohn volumio[30592]: info: ------------------------------------------- Apr 03 13:56:17 danjohn volumio[30592]: info: ----- System startup ---- Apr 03 13:56:17 danjohn volumio[30592]: info: ------------------------------------------- Apr 03 13:56:18 danjohn volumio[30592]: info: MYVOLUMIO Environment detected Apr 03 13:56:18 danjohn volumio[30592]: info: Plugin folders cleanup Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning into folder /volumio/app/plugins/ Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning category audio_interface Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning category miscellanea Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning category music_service Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning category plugins.json Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning category system_controller Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning category user_interface Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning into folder /data/plugins/ Apr 03 13:56:18 danjohn volumio[30592]: info: Scanning category system_controller Apr 03 13:56:18 danjohn volumio[30592]: info: Plugin folders cleanup completed Apr 03 13:56:18 danjohn volumio[30592]: info: ------------------------------------------- Apr 03 13:56:18 danjohn volumio[30592]: info: ----- Core plugins startup ---- Apr 03 13:56:18 danjohn volumio[30592]: info: ------------------------------------------- Apr 03 13:56:18 danjohn volumio[30592]: info: Loading plugins from folder /volumio/app/plugins/ Apr 03 13:56:18 danjohn volumio[30592]: info: Adding plugin upnp to MyMusic Plugins Apr 03 13:56:18 danjohn volumio[30592]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 03 13:56:18 danjohn volumio[30592]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 03 13:56:18 danjohn volumio[30592]: info: Loading plugins from folder /data/plugins/ Apr 03 13:56:18 danjohn volumio[30592]: info: Loading plugin "system"... Apr 03 13:56:18 danjohn volumio[30592]: info: Loading plugin "appearance"... Apr 03 13:56:18 danjohn volumio[30592]: info: Loading plugin "network"... Apr 03 13:56:18 danjohn volumio[30592]: info: Refreshing Cached IP Addresses Apr 03 13:56:18 danjohn sudo[30618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 13:56:18 danjohn sudo[30618]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:18 danjohn sudo[30618]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:18 danjohn sudo[30620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 13:56:18 danjohn sudo[30620]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:18 danjohn volumio[30592]: info: Loading plugin "services"... Apr 03 13:56:18 danjohn volumio[30592]: info: Loading plugin "alsa_controller"... Apr 03 13:56:18 danjohn sudo[30620]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:18 danjohn sudo[30623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 03 13:56:18 danjohn sudo[30623]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 13:56:19 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "wizard"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "networkfs"... Apr 03 13:56:19 danjohn volumio[30592]: info: Cannot mount NAS AHTE at system boot, trial number 1 ,retrying in 5 seconds Apr 03 13:56:19 danjohn volumio[30592]: info: Starting Udev Watcher for removable devices Apr 03 13:56:19 danjohn volumio[30592]: info: Ignoring mount for partition: boot Apr 03 13:56:19 danjohn volumio[30592]: info: Ignoring mount for partition: volumio Apr 03 13:56:19 danjohn volumio[30592]: info: Ignoring mount for partition: volumio_data Apr 03 13:56:19 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "volumio_command_line_client"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "upnp"... Apr 03 13:56:19 danjohn volumio[30592]: info: [1712141779091] Starting Upmpd Daemon Apr 03 13:56:19 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "my_music"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "mpd"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "upnp_browser"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "alarm-clock"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "airplay_emulation"... Apr 03 13:56:19 danjohn volumio[30592]: info: Starting Shairport Sync Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "last_100"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "webradio"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "i2s_dacs"... Apr 03 13:56:19 danjohn volumio[30592]: info: I2S DAC not set, start Auto-detection Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "volumiodiscovery"... Apr 03 13:56:19 danjohn volumio[30592]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:19 danjohn volumio[30592]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:19 danjohn node[30592]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:19 danjohn volumio[30592]: *** WARNING *** For more information see Apr 03 13:56:19 danjohn volumio[30592]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:19 danjohn volumio[30592]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:19 danjohn volumio[30592]: *** WARNING *** For more information see Apr 03 13:56:19 danjohn node[30592]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:19 danjohn node[30592]: *** WARNING *** For more information see Apr 03 13:56:19 danjohn node[30592]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 13:56:19 danjohn node[30592]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 13:56:19 danjohn node[30592]: *** WARNING *** For more information see Apr 03 13:56:19 danjohn volumio[30592]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 03 13:56:19 danjohn volumio[30592]: info: Discovery: Started advertising with name: danjohn Apr 03 13:56:19 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "autostart"... Apr 03 13:56:19 danjohn volumio[30592]: info: Applying required configuration parameters for plugin autostart Apr 03 13:56:19 danjohn volumio[30592]: info: AutoStart - onVolumioStart - read config.json Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "outputs"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "albumart"... Apr 03 13:56:19 danjohn volumio[30592]: info: Plugin example_plugin is not enabled Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "inputs"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "updater_comm"... Apr 03 13:56:19 danjohn volumio[30592]: info: Plugin mpdemulation is not enabled Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "rest_api"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading plugin "websocket"... Apr 03 13:56:19 danjohn volumio[30592]: info: Loading i18n strings for locale en Apr 03 13:56:20 danjohn volumio[30592]: Updating browse sources language Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::initPlayerControls Apr 03 13:56:20 danjohn volumio[30592]: Forking 3 albumart workers Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: Express server listening on port 3000 Apr 03 13:56:20 danjohn volumio[30592]: [Metrics] WebUI: 2s 586.69ms Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::resetVolumioState Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::getcurrentVolume Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 13:56:20 danjohn volumio[30592]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 1 Apr 03 13:56:20 danjohn volumio[30592]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 2 Apr 03 13:56:20 danjohn volumio[30592]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3 Apr 03 13:56:20 danjohn volumio[30592]: info: Reloading queue from file Apr 03 13:56:20 danjohn volumio[30592]: info: Setting Device type: Raspberry PI Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::setRepeat false single undefined Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::pushState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::setRandom false Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::pushState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:20 danjohn volumio[30592]: info: VolumeController:: Volume=62 Mute =false Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::pushState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::updateTrackBlock Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrackBlock Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 13:56:20 danjohn volumio[30592]: verbose: New Socket.io Connection to 192.168.1.56 from 192.168.1.222 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: Received Get System Info Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: Getting this device information Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: Starting albumart workers Apr 03 13:56:20 danjohn volumio[30592]: info: Received Get System Info Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: Getting this device information Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 03 13:56:20 danjohn volumio[30592]: info: Received Get System Info Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: Getting this device information Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: Listing playlists Apr 03 13:56:20 danjohn volumio[30592]: info: Received Get System Info Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: Getting this device information Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 13:56:20 danjohn volumio[30592]: Starting albumart workers Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: Starting albumart workers Apr 03 13:56:20 danjohn volumio[30592]: info: VolumeController:: Volume=62 Mute =false Apr 03 13:56:20 danjohn volumio[30592]: info: CoreStateMachine::pushState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioPushState Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: adding 2ec3c2bd-f66e-4a2d-a89c-cfac2168356e Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: Found device danjohn Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: this is already registered, 2ec3c2bd-f66e-4a2d-a89c-cfac2168356e Apr 03 13:56:20 danjohn volumio[30592]: info: Discovery: Found device danjohn Apr 03 13:56:20 danjohn volumio[30592]: info: CoreCommandRouter::volumioGetState Apr 03 13:56:20 danjohn volumio[30592]: info: CorePlayQueue::getTrack 0 Apr 03 13:56:21 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:21] [connect] Successful connection Apr 03 13:56:21 danjohn volumio-remote-updater[488]: [2024-04-03 13:56:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712141781 101 Apr 03 13:56:21 danjohn volumio[30592]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Apr 03 13:56:22 danjohn sudo[30623]: pam_unix(sudo:session): session closed for user root Apr 03 13:56:24 danjohn volumio[30592]: info: Cannot mount NAS AHTE at system boot, trial number 2 ,retrying in 5 seconds Apr 03 13:56:24 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 13:56:24 danjohn volumio[30592]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 03 13:56:24 danjohn volumio[30592]: verbose: ControllerMpd::sendMpdCommand rescan Apr 03 13:56:24 danjohn volumio[30592]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:56:24 danjohn volumio[30592]: TypeError: Cannot read property 'then' of undefined Apr 03 13:56:24 danjohn volumio[30592]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Apr 03 13:56:24 danjohn volumio[30592]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1885:15) Apr 03 13:56:24 danjohn volumio[30592]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Apr 03 13:56:24 danjohn volumio[30592]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1045:26) Apr 03 13:56:24 danjohn volumio[30592]: at Socket.emit (events.js:315:20) Apr 03 13:56:24 danjohn volumio[30592]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 03 13:56:24 danjohn volumio[30592]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 03 13:56:24 danjohn volumio[30592]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 13:56:24 danjohn sudo[30706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-03 13:55 Apr 03 13:56:24 danjohn sudo[30706]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"