-- 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"