-- Logs begin at Thu 2024-04-18 12:25:44 CEST, end at Thu 2024-04-18 13:35:12 CEST. -- Apr 18 13:34:00 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:04 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:08 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:12 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:15 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Apr 18 13:34:15 volumio-salonw volumio[20060]: info: Adding a new share Apr 18 13:34:15 volumio-salonw volumio[20060]: info: No correspondence found in configuration for share NAS-ALAINDIV on IP 192.168.6.29 Apr 18 13:34:15 volumio-salonw sudo[20557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.29/music /mnt/NAS/NAS-ALAINDIV Apr 18 13:34:15 volumio-salonw sudo[20557]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:15 volumio-salonw kernel: Key type cifs.spnego registered Apr 18 13:34:15 volumio-salonw kernel: Key type cifs.idmap registered Apr 18 13:34:15 volumio-salonw kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 18 13:34:15 volumio-salonw kernel: CIFS: Attempting to mount \\192.168.6.29\music Apr 18 13:34:16 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:20 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:24 volumio-salonw sudo[20571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 13:34:24 volumio-salonw sudo[20571]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:24 volumio-salonw sudo[20571]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:24 volumio-salonw sudo[20574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 13:34:24 volumio-salonw sudo[20574]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:24 volumio-salonw sudo[20574]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:24 volumio-salonw volumio[20060]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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: 6 Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Retrieving Cloud Streaming UI Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Getting Tidal Cloud Configuration Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Getting Qobuz Cloud Configuration Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Asking plugin for UI Config Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Getting Spotify Cloud Configuration Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Asking plugin for UI Config Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Saving Spotify Acccount Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Got it Apr 18 13:34:24 volumio-salonw volumio[20060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Got Tidal Cloud Configuration Apr 18 13:34:24 volumio-salonw volumio[20060]: info: Got it Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:24 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 18 13:34:25 volumio-salonw sudo[20557]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:25 volumio-salonw volumio[20060]: info: Error mounting NAS-ALAINDIV on IP 192.168.6.29 : (115): Operation now in progress Apr 18 13:34:25 volumio-salonw volumio[20060]: Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) Apr 18 13:34:25 volumio-salonw kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 18 13:34:25 volumio-salonw kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 18 13:34:26 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 18 13:34:26 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 13:34:27 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 13:34:28 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:34:31 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:34:31 volumio-salonw volumio[20060]: info: Preload queue cleared Apr 18 13:34:31 volumio-salonw volumio[20060]: info: CoreCommandRouter::volumioGetQueue Apr 18 13:34:31 volumio-salonw volumio[20060]: info: CoreStateMachine::getQueue Apr 18 13:34:31 volumio-salonw volumio[20060]: info: CorePlayQueue::getQueue Apr 18 13:34:31 volumio-salonw volumio[20060]: info: Listing playlists Apr 18 13:34:34 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:34:34 volumio-salonw volumio[20060]: info: Preload queue cleared Apr 18 13:34:36 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:34:36 volumio-salonw volumio[20060]: info: Preload queue cleared Apr 18 13:34:38 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:34:38 volumio-salonw volumio[20060]: info: Preload queue cleared Apr 18 13:34:40 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:34:40 volumio-salonw volumio[20060]: info: Preload queue cleared Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 18 13:34:42 volumio-salonw volumio[20060]: info: Received Get System Version Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 13:34:42 volumio-salonw volumio[20060]: info: Received Get System Info Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 18 13:34:42 volumio-salonw volumio[20060]: info: Discovery: Getting this device information Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 18 13:34:42 volumio-salonw volumio[20060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:34:42 volumio-salonw volumio[20060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:34:42 volumio-salonw volumio[20060]: TypeError: Cannot read property 'length' of undefined Apr 18 13:34:42 volumio-salonw volumio[20060]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 18 13:34:42 volumio-salonw volumio[20060]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 18 13:34:42 volumio-salonw volumio[20060]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 18 13:34:42 volumio-salonw volumio[20060]: at Parser.emit (events.js:315:20) Apr 18 13:34:42 volumio-salonw volumio[20060]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 18 13:34:42 volumio-salonw volumio[20060]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 18 13:34:42 volumio-salonw volumio[20060]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 18 13:34:42 volumio-salonw volumio[20060]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 18 13:34:42 volumio-salonw volumio[20060]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 18 13:34:42 volumio-salonw volumio[20060]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 18 13:34:42 volumio-salonw volumio[20060]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 18 13:34:42 volumio-salonw volumio[20060]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 18 13:34:42 volumio-salonw volumio[20060]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 18 13:34:42 volumio-salonw volumio[20060]: at IncomingMessage.emit (events.js:327:22) Apr 18 13:34:42 volumio-salonw volumio[20060]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 18 13:34:42 volumio-salonw volumio[20060]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 13:34:42 volumio-salonw volumio[20060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:34:42 volumio-salonw sudo[20592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 13:33 Apr 18 13:34:42 volumio-salonw sudo[20592]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:42 volumio-salonw sudo[20592]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:42 volumio-salonw vtcs[20445]: [2024-04-18 13:34:42.760] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Apr 18 13:34:42 volumio-salonw go-librespot[20276]: time="2024-04-18T13:34:42+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Apr 18 13:34:42 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 13:34:42 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 13:34:42 volumio-salonw systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 13:34:42 volumio-salonw systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 13:34:42 volumio-salonw systemd[1]: Started dynamicswap service. Apr 18 13:34:42 volumio-salonw systemd[1]: dynamicswap.service: Succeeded. Apr 18 13:34:43 volumio-salonw systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 13:34:43 volumio-salonw systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Apr 18 13:34:43 volumio-salonw systemd[1]: Started dynamicswap service. Apr 18 13:34:43 volumio-salonw systemd[1]: Stopped Volumio Backend Module. Apr 18 13:34:43 volumio-salonw systemd[1]: Started Volumio Backend Module. Apr 18 13:34:43 volumio-salonw systemd[1]: dynamicswap.service: Succeeded. Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ------------------------------------------- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ----- Volumio3 ---- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ------------------------------------------- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ----- System startup ---- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ------------------------------------------- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: MYVOLUMIO Environment detected Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Plugin folders cleanup Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning into folder /volumio/app/plugins/ Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category audio_interface Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category miscellanea Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category music_service Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category plugins.json Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category system_controller Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category user_interface Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning into folder /data/plugins/ Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category music_service Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Scanning category system_controller Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Plugin folders cleanup completed Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ------------------------------------------- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ----- Core plugins startup ---- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: ------------------------------------------- Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Adding plugin upnp to MyMusic Plugins Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugins from folder /data/plugins/ Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "system"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "appearance"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "network"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Refreshing Cached IP Addresses Apr 18 13:34:43 volumio-salonw sudo[20630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 13:34:43 volumio-salonw sudo[20630]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:43 volumio-salonw sudo[20630]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:43 volumio-salonw sudo[20632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 13:34:43 volumio-salonw sudo[20632]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "services"... Apr 18 13:34:43 volumio-salonw sudo[20632]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "alsa_controller"... Apr 18 13:34:43 volumio-salonw sudo[20635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 13:34:43 volumio-salonw sudo[20635]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:43 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "wizard"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "networkfs"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Starting Udev Watcher for removable devices Apr 18 13:34:43 volumio-salonw sudo[20643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.29/music /mnt/NAS/NAS-ALAINDIV Apr 18 13:34:43 volumio-salonw sudo[20643]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Ignoring mount for partition: boot Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Ignoring mount for partition: volumio Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Ignoring mount for partition: volumio_data Apr 18 13:34:43 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "volumio_command_line_client"... Apr 18 13:34:43 volumio-salonw kernel: CIFS: Attempting to mount \\192.168.6.29\music Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "upnp"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: [1713440083905] Starting Upmpd Daemon Apr 18 13:34:43 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "my_music"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "mpd"... Apr 18 13:34:43 volumio-salonw volumio[20604]: info: Loading plugin "upnp_browser"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "alarm-clock"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "airplay_emulation"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Starting Shairport Sync Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "last_100"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "webradio"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "i2s_dacs"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: I2S DAC not set, start Auto-detection Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "volumiodiscovery"... Apr 18 13:34:44 volumio-salonw volumio[20604]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:44 volumio-salonw node[20604]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:44 volumio-salonw node[20604]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:44 volumio-salonw volumio[20604]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:44 volumio-salonw volumio[20604]: *** WARNING *** For more information see Apr 18 13:34:44 volumio-salonw volumio[20604]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:44 volumio-salonw volumio[20604]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:44 volumio-salonw volumio[20604]: *** WARNING *** For more information see Apr 18 13:34:44 volumio-salonw node[20604]: *** WARNING *** For more information see Apr 18 13:34:44 volumio-salonw node[20604]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:44 volumio-salonw node[20604]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:44 volumio-salonw node[20604]: *** WARNING *** For more information see Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Discovery: Started advertising with name: Volumio-SalonW Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "bandcamp"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "mixcloud"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "spop"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "autostart"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Applying required configuration parameters for plugin autostart Apr 18 13:34:44 volumio-salonw volumio[20604]: info: AutoStart - onVolumioStart - read config.json Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "outputs"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "albumart"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Plugin example_plugin is not enabled Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "inputs"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "updater_comm"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Plugin mpdemulation is not enabled Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "rest_api"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "websocket"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading plugin "minidlna"... Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Applying required configuration parameters for plugin minidlna Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Loading i18n strings for locale fr Apr 18 13:34:44 volumio-salonw volumio[20604]: Forking 3 albumart workers Apr 18 13:34:44 volumio-salonw volumio[20604]: Updating browse sources language Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::initPlayerControls Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: Express server listening on port 3000 Apr 18 13:34:44 volumio-salonw volumio[20604]: [Metrics] WebUI: 1s 474.55ms Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::resetVolumioState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::getcurrentVolume Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:34:44 volumio-salonw volumio[20604]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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 18 13:34:44 volumio-salonw volumio[20604]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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 18 13:34:44 volumio-salonw volumio[20604]: info: Reloading queue from file Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::setRepeat null single undefined Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::pushState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::setRandom null Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::pushState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: VolumeController:: Volume=100 Mute =false Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::pushState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::updateTrackBlock Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrackBlock Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:34:44 volumio-salonw volumio[20604]: Starting albumart workers Apr 18 13:34:44 volumio-salonw volumio[20604]: Starting albumart workers Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Setting Device type: Raspberry PI Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioGetQueue Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CoreStateMachine::getQueue Apr 18 13:34:44 volumio-salonw volumio[20604]: info: CorePlayQueue::getQueue Apr 18 13:34:44 volumio-salonw volumio[20604]: info: Listing playlists Apr 18 13:34:45 volumio-salonw volumio[20604]: Starting albumart workers Apr 18 13:34:45 volumio-salonw volumio[20604]: info: VolumeController:: Volume=100 Mute =false Apr 18 13:34:45 volumio-salonw volumio[20604]: info: CoreStateMachine::pushState Apr 18 13:34:45 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:45 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:45 volumio-salonw volumio[20604]: info: Discovery: adding 98185564-5cef-498a-b7a6-1aa6f9fb3e1e Apr 18 13:34:45 volumio-salonw volumio[20604]: info: Discovery: Found device Volumio-SalonW Apr 18 13:34:45 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:45 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:45 volumio-salonw volumio[20604]: info: Discovery: this is already registered, 98185564-5cef-498a-b7a6-1aa6f9fb3e1e Apr 18 13:34:45 volumio-salonw volumio[20604]: info: Discovery: Found device Volumio-SalonW Apr 18 13:34:45 volumio-salonw volumio[20604]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:45 volumio-salonw volumio[20604]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:47 volumio-salonw sudo[20635]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:47 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:47] [connect] Successful connection Apr 18 13:34:47 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713440087 101 Apr 18 13:34:47 volumio-salonw volumio[20604]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 18 13:34:49 volumio-salonw sudo[20643]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:49 volumio-salonw kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 18 13:34:49 volumio-salonw kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 18 13:34:49 volumio-salonw volumio[20604]: info: Cannot mount NAS NAS-ALAINDIV at system boot, trial number 1 ,retrying in 5 seconds Apr 18 13:34:49 volumio-salonw volumio[20604]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:34:49 volumio-salonw volumio[20604]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:34:49 volumio-salonw volumio[20604]: TypeError: Cannot read property 'length' of undefined Apr 18 13:34:49 volumio-salonw volumio[20604]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 18 13:34:49 volumio-salonw volumio[20604]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 18 13:34:49 volumio-salonw volumio[20604]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 18 13:34:49 volumio-salonw volumio[20604]: at Parser.emit (events.js:315:20) Apr 18 13:34:49 volumio-salonw volumio[20604]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 18 13:34:49 volumio-salonw volumio[20604]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 18 13:34:49 volumio-salonw volumio[20604]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 18 13:34:49 volumio-salonw volumio[20604]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 18 13:34:49 volumio-salonw volumio[20604]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 18 13:34:49 volumio-salonw volumio[20604]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 18 13:34:49 volumio-salonw volumio[20604]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 18 13:34:49 volumio-salonw volumio[20604]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 18 13:34:49 volumio-salonw volumio[20604]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 18 13:34:49 volumio-salonw volumio[20604]: at IncomingMessage.emit (events.js:327:22) Apr 18 13:34:49 volumio-salonw volumio[20604]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 18 13:34:49 volumio-salonw volumio[20604]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 13:34:49 volumio-salonw volumio[20604]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:34:49 volumio-salonw sudo[20725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 13:33 Apr 18 13:34:49 volumio-salonw sudo[20725]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:49 volumio-salonw sudo[20725]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:49 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 13:34:49 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 13:34:49 volumio-salonw systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 13:34:49 volumio-salonw systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 13:34:49 volumio-salonw systemd[1]: Started dynamicswap service. Apr 18 13:34:49 volumio-salonw systemd[1]: dynamicswap.service: Succeeded. Apr 18 13:34:50 volumio-salonw systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 13:34:50 volumio-salonw systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Apr 18 13:34:50 volumio-salonw systemd[1]: Stopped Volumio Backend Module. Apr 18 13:34:50 volumio-salonw systemd[1]: Started Volumio Backend Module. Apr 18 13:34:50 volumio-salonw systemd[1]: Started dynamicswap service. Apr 18 13:34:50 volumio-salonw systemd[1]: dynamicswap.service: Succeeded. Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ------------------------------------------- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ----- Volumio3 ---- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ------------------------------------------- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ----- System startup ---- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ------------------------------------------- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: MYVOLUMIO Environment detected Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Plugin folders cleanup Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning into folder /volumio/app/plugins/ Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category audio_interface Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category miscellanea Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category music_service Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category plugins.json Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category system_controller Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category user_interface Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning into folder /data/plugins/ Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category music_service Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Scanning category system_controller Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Plugin folders cleanup completed Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ------------------------------------------- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ----- Core plugins startup ---- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: ------------------------------------------- Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Adding plugin upnp to MyMusic Plugins Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Loading plugins from folder /data/plugins/ Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Loading plugin "system"... Apr 18 13:34:50 volumio-salonw volumio[20735]: info: Loading plugin "appearance"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "network"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Refreshing Cached IP Addresses Apr 18 13:34:51 volumio-salonw sudo[20762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 13:34:51 volumio-salonw sudo[20762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:51 volumio-salonw sudo[20762]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:51 volumio-salonw sudo[20764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 13:34:51 volumio-salonw sudo[20764]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "services"... Apr 18 13:34:51 volumio-salonw sudo[20764]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "alsa_controller"... Apr 18 13:34:51 volumio-salonw sudo[20767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 13:34:51 volumio-salonw sudo[20767]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:51 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "wizard"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "networkfs"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Starting Udev Watcher for removable devices Apr 18 13:34:51 volumio-salonw sudo[20775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.29/music /mnt/NAS/NAS-ALAINDIV Apr 18 13:34:51 volumio-salonw sudo[20775]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Ignoring mount for partition: boot Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Ignoring mount for partition: volumio Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Ignoring mount for partition: volumio_data Apr 18 13:34:51 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "volumio_command_line_client"... Apr 18 13:34:51 volumio-salonw kernel: CIFS: Attempting to mount \\192.168.6.29\music Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "upnp"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: [1713440091176] Starting Upmpd Daemon Apr 18 13:34:51 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "my_music"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "mpd"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "upnp_browser"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "alarm-clock"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "airplay_emulation"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Starting Shairport Sync Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "last_100"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "webradio"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "i2s_dacs"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: I2S DAC not set, start Auto-detection Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "volumiodiscovery"... Apr 18 13:34:51 volumio-salonw volumio[20735]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:51 volumio-salonw node[20735]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:51 volumio-salonw node[20735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:51 volumio-salonw volumio[20735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:51 volumio-salonw volumio[20735]: *** WARNING *** For more information see Apr 18 13:34:51 volumio-salonw volumio[20735]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:51 volumio-salonw volumio[20735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:51 volumio-salonw volumio[20735]: *** WARNING *** For more information see Apr 18 13:34:51 volumio-salonw node[20735]: *** WARNING *** For more information see Apr 18 13:34:51 volumio-salonw node[20735]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 13:34:51 volumio-salonw node[20735]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:34:51 volumio-salonw node[20735]: *** WARNING *** For more information see Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Discovery: Started advertising with name: Volumio-SalonW Apr 18 13:34:51 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "bandcamp"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "mixcloud"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "spop"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "autostart"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Applying required configuration parameters for plugin autostart Apr 18 13:34:51 volumio-salonw volumio[20735]: info: AutoStart - onVolumioStart - read config.json Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "outputs"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "albumart"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Plugin example_plugin is not enabled Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "inputs"... Apr 18 13:34:51 volumio-salonw volumio[20735]: info: Loading plugin "updater_comm"... Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Plugin mpdemulation is not enabled Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Loading plugin "rest_api"... Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Loading plugin "websocket"... Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Loading plugin "minidlna"... Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Applying required configuration parameters for plugin minidlna Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Loading i18n strings for locale fr Apr 18 13:34:52 volumio-salonw volumio[20735]: Forking 3 albumart workers Apr 18 13:34:52 volumio-salonw volumio[20735]: Updating browse sources language Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::initPlayerControls Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: Express server listening on port 3000 Apr 18 13:34:52 volumio-salonw volumio[20735]: [Metrics] WebUI: 1s 508.60ms Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::resetVolumioState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::getcurrentVolume Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:34:52 volumio-salonw volumio[20735]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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 18 13:34:52 volumio-salonw volumio[20735]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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 18 13:34:52 volumio-salonw volumio[20735]: info: Reloading queue from file Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::setRepeat null single undefined Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::pushState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::setRandom null Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::pushState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: VolumeController:: Volume=100 Mute =false Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::pushState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::updateTrackBlock Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrackBlock Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Setting Device type: Raspberry PI Apr 18 13:34:52 volumio-salonw volumio[20735]: Starting albumart workers Apr 18 13:34:52 volumio-salonw volumio[20735]: Starting albumart workers Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Discovery: adding 98185564-5cef-498a-b7a6-1aa6f9fb3e1e Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Discovery: Found device Volumio-SalonW Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Discovery: this is already registered, 98185564-5cef-498a-b7a6-1aa6f9fb3e1e Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Discovery: Found device Volumio-SalonW Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:52 volumio-salonw volumio[20735]: info: VolumeController:: Volume=100 Mute =false Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::pushState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioPushState Apr 18 13:34:52 volumio-salonw volumio[20735]: Starting albumart workers Apr 18 13:34:52 volumio-salonw volumio[20735]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioGetState Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getTrack 0 Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreCommandRouter::volumioGetQueue Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CoreStateMachine::getQueue Apr 18 13:34:52 volumio-salonw volumio[20735]: info: CorePlayQueue::getQueue Apr 18 13:34:52 volumio-salonw volumio[20735]: info: Listing playlists Apr 18 13:34:54 volumio-salonw sudo[20767]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:54 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:54] [connect] Successful connection Apr 18 13:34:54 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:34:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713440094 101 Apr 18 13:34:54 volumio-salonw volumio[20735]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Apr 18 13:34:55 volumio-salonw sudo[20775]: pam_unix(sudo:session): session closed for user root Apr 18 13:34:55 volumio-salonw volumio[20735]: info: Cannot mount NAS NAS-ALAINDIV at system boot, trial number 1 ,retrying in 5 seconds Apr 18 13:34:55 volumio-salonw kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 18 13:34:55 volumio-salonw kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 18 13:34:59 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 13:34:59 volumio-salonw volumio[20735]: info: CURURI: music-library Apr 18 13:34:59 volumio-salonw volumio[20735]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 18 13:35:00 volumio-salonw sudo[20849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.29/music /mnt/NAS/NAS-ALAINDIV Apr 18 13:35:00 volumio-salonw sudo[20849]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:00 volumio-salonw kernel: CIFS: Attempting to mount \\192.168.6.29\music Apr 18 13:35:01 volumio-salonw sudo[20854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 13:35:01 volumio-salonw sudo[20854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:01 volumio-salonw sudo[20854]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:01 volumio-salonw sudo[20857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 13:35:01 volumio-salonw sudo[20857]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:01 volumio-salonw sudo[20857]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:01 volumio-salonw sudo[20860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 18 13:35:01 volumio-salonw sudo[20860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:01 volumio-salonw sudo[20860]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:01 volumio-salonw volumio[20735]: info: Upmpdcli Daemon Started Apr 18 13:35:02 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:35:02 volumio-salonw volumio[20735]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:35:02 volumio-salonw volumio[20735]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:35:02 volumio-salonw volumio[20735]: TypeError: Cannot read property 'length' of undefined Apr 18 13:35:02 volumio-salonw volumio[20735]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 18 13:35:02 volumio-salonw volumio[20735]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 18 13:35:02 volumio-salonw volumio[20735]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 18 13:35:02 volumio-salonw volumio[20735]: at Parser.emit (events.js:315:20) Apr 18 13:35:02 volumio-salonw volumio[20735]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 18 13:35:02 volumio-salonw volumio[20735]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 18 13:35:02 volumio-salonw volumio[20735]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 18 13:35:02 volumio-salonw volumio[20735]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 18 13:35:02 volumio-salonw volumio[20735]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 18 13:35:02 volumio-salonw volumio[20735]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 18 13:35:02 volumio-salonw volumio[20735]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 18 13:35:02 volumio-salonw volumio[20735]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 18 13:35:02 volumio-salonw volumio[20735]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 18 13:35:02 volumio-salonw volumio[20735]: at IncomingMessage.emit (events.js:327:22) Apr 18 13:35:02 volumio-salonw volumio[20735]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 18 13:35:02 volumio-salonw volumio[20735]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 13:35:02 volumio-salonw volumio[20735]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:35:03 volumio-salonw sudo[20872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 13:34 Apr 18 13:35:03 volumio-salonw sudo[20872]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:03 volumio-salonw sudo[20872]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:03 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:35:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 18 13:35:03 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:35:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 18 13:35:03 volumio-salonw systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 18 13:35:03 volumio-salonw kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 18 13:35:03 volumio-salonw kernel: CIFS: VFS: cifs_mount failed w/return code = -4 Apr 18 13:35:03 volumio-salonw sudo[20849]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:03 volumio-salonw systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 18 13:35:03 volumio-salonw systemd[1]: Started dynamicswap service. Apr 18 13:35:03 volumio-salonw systemd[1]: dynamicswap.service: Succeeded. Apr 18 13:35:03 volumio-salonw systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 18 13:35:03 volumio-salonw systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Apr 18 13:35:03 volumio-salonw systemd[1]: Started dynamicswap service. Apr 18 13:35:03 volumio-salonw systemd[1]: Stopped Volumio Backend Module. Apr 18 13:35:03 volumio-salonw systemd[1]: Started Volumio Backend Module. Apr 18 13:35:03 volumio-salonw systemd[1]: dynamicswap.service: Succeeded. Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ------------------------------------------- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ----- Volumio3 ---- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ------------------------------------------- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ----- System startup ---- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ------------------------------------------- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: MYVOLUMIO Environment detected Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Plugin folders cleanup Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning into folder /volumio/app/plugins/ Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category audio_interface Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category miscellanea Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category music_service Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category plugins.json Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category system_controller Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category user_interface Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning into folder /data/plugins/ Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category music_service Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Scanning category system_controller Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Plugin folders cleanup completed Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ------------------------------------------- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ----- Core plugins startup ---- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: ------------------------------------------- Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Loading plugins from folder /volumio/app/plugins/ Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Adding plugin upnp to MyMusic Plugins Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Loading plugins from folder /data/plugins/ Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Loading plugin "system"... Apr 18 13:35:03 volumio-salonw volumio[20883]: info: Loading plugin "appearance"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "network"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Refreshing Cached IP Addresses Apr 18 13:35:04 volumio-salonw sudo[20909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 18 13:35:04 volumio-salonw sudo[20909]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:04 volumio-salonw sudo[20909]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:04 volumio-salonw sudo[20911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 18 13:35:04 volumio-salonw sudo[20911]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "services"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "alsa_controller"... Apr 18 13:35:04 volumio-salonw sudo[20911]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:04 volumio-salonw sudo[20914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 18 13:35:04 volumio-salonw sudo[20914]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:04 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "wizard"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "networkfs"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Starting Udev Watcher for removable devices Apr 18 13:35:04 volumio-salonw sudo[20922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.6.29/music /mnt/NAS/NAS-ALAINDIV Apr 18 13:35:04 volumio-salonw sudo[20922]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Ignoring mount for partition: boot Apr 18 13:35:04 volumio-salonw kernel: CIFS: Attempting to mount \\192.168.6.29\music Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Ignoring mount for partition: volumio Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Ignoring mount for partition: volumio_data Apr 18 13:35:04 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "volumio_command_line_client"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "upnp"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: [1713440104174] Starting Upmpd Daemon Apr 18 13:35:04 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "my_music"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "mpd"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "upnp_browser"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "alarm-clock"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "airplay_emulation"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Starting Shairport Sync Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "last_100"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "webradio"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "i2s_dacs"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: I2S DAC not set, start Auto-detection Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "volumiodiscovery"... Apr 18 13:35:04 volumio-salonw volumio[20883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 13:35:04 volumio-salonw node[20883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 18 13:35:04 volumio-salonw node[20883]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:35:04 volumio-salonw volumio[20883]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:35:04 volumio-salonw volumio[20883]: *** WARNING *** For more information see Apr 18 13:35:04 volumio-salonw volumio[20883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 13:35:04 volumio-salonw volumio[20883]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:35:04 volumio-salonw volumio[20883]: *** WARNING *** For more information see Apr 18 13:35:04 volumio-salonw node[20883]: *** WARNING *** For more information see Apr 18 13:35:04 volumio-salonw node[20883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 18 13:35:04 volumio-salonw node[20883]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 18 13:35:04 volumio-salonw node[20883]: *** WARNING *** For more information see Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Discovery: Started advertising with name: Volumio-SalonW Apr 18 13:35:04 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "bandcamp"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "mixcloud"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "spop"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "autostart"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Applying required configuration parameters for plugin autostart Apr 18 13:35:04 volumio-salonw volumio[20883]: info: AutoStart - onVolumioStart - read config.json Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "outputs"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "albumart"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Plugin example_plugin is not enabled Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "inputs"... Apr 18 13:35:04 volumio-salonw volumio[20883]: info: Loading plugin "updater_comm"... Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Plugin mpdemulation is not enabled Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Loading plugin "rest_api"... Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Loading plugin "websocket"... Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Loading plugin "minidlna"... Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Applying required configuration parameters for plugin minidlna Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Loading i18n strings for locale fr Apr 18 13:35:05 volumio-salonw volumio[20883]: Forking 3 albumart workers Apr 18 13:35:05 volumio-salonw volumio[20883]: Updating browse sources language Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::initPlayerControls Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: Express server listening on port 3000 Apr 18 13:35:05 volumio-salonw volumio[20883]: [Metrics] WebUI: 1s 504.91ms Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::resetVolumioState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::getcurrentVolume Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 13:35:05 volumio-salonw volumio[20883]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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 18 13:35:05 volumio-salonw volumio[20883]: info: Reloading queue from file Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::setRepeat null single undefined Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::pushState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrack 0 Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioPushState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::setRandom null Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::pushState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrack 0 Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioPushState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Setting Device type: Raspberry PI Apr 18 13:35:05 volumio-salonw volumio[20883]: info: VolumeController:: Volume=100 Mute =false Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::pushState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrack 0 Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioPushState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::updateTrackBlock Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrackBlock Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 13:35:05 volumio-salonw volumio[20883]: info: VolumeController:: Volume=100 Mute =false Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::pushState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrack 0 Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioPushState Apr 18 13:35:05 volumio-salonw volumio[20883]: Starting albumart workers Apr 18 13:35:05 volumio-salonw volumio[20883]: Starting albumart workers Apr 18 13:35:05 volumio-salonw volumio[20883]: Starting albumart workers Apr 18 13:35:05 volumio-salonw volumio[20883]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 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 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioGetState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrack 0 Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioGetVisibleSources Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioGetQueue Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreStateMachine::getQueue Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getQueue Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Listing playlists Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Discovery: adding 98185564-5cef-498a-b7a6-1aa6f9fb3e1e Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Discovery: Found device Volumio-SalonW Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioGetState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrack 0 Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Discovery: this is already registered, 98185564-5cef-498a-b7a6-1aa6f9fb3e1e Apr 18 13:35:05 volumio-salonw volumio[20883]: info: Discovery: Found device Volumio-SalonW Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CoreCommandRouter::volumioGetState Apr 18 13:35:05 volumio-salonw volumio[20883]: info: CorePlayQueue::getTrack 0 Apr 18 13:35:07 volumio-salonw sudo[20914]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:08 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:35:08] [connect] Successful connection Apr 18 13:35:08 volumio-salonw volumio-remote-updater[705]: [2024-04-18 13:35:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713440108 101 Apr 18 13:35:08 volumio-salonw volumio[20883]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 18 13:35:08 volumio-salonw sudo[20922]: pam_unix(sudo:session): session closed for user root Apr 18 13:35:08 volumio-salonw kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 18 13:35:08 volumio-salonw kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 18 13:35:08 volumio-salonw volumio[20883]: info: Cannot mount NAS NAS-ALAINDIV at system boot, trial number 1 ,retrying in 5 seconds Apr 18 13:35:10 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 13:35:10 volumio-salonw volumio[20883]: info: CURURI: artists:// Apr 18 13:35:10 volumio-salonw volumio[20883]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'sendCommand' of undefined Apr 18 13:35:12 volumio-salonw volumio[20883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 13:35:12 volumio-salonw volumio[20883]: info: CURURI: albums:// Apr 18 13:35:12 volumio-salonw volumio[20883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:35:12 volumio-salonw volumio[20883]: TypeError: Cannot read property 'sendCommand' of undefined Apr 18 13:35:12 volumio-salonw volumio[20883]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 Apr 18 13:35:12 volumio-salonw volumio[20883]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 18 13:35:12 volumio-salonw volumio[20883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:35:12 volumio-salonw sudo[21063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 13:34 Apr 18 13:35:12 volumio-salonw sudo[21063]: 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"