-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Thu 2025-05-01 10:34:57 MSK. -- May 01 10:33:01 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 10:33:05 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 10:33:09 volumio volumio[1223]: info: CoreCommandRouter::volumioGetState May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 01 10:33:11 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 10:33:14 volumio volumio[1223]: info: CoreCommandRouter::volumioGetState May 01 10:33:15 volumio volumio[1223]: info: CoreCommandRouter::volumioGetQueue May 01 10:33:15 volumio volumio[1223]: info: CoreStateMachine::getQueue May 01 10:33:15 volumio volumio[1223]: info: CorePlayQueue::getQueue May 01 10:33:18 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:33:18 volumio volumio[1223]: info: Preload queue cleared May 01 10:33:22 volumio volumio[1223]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:33:22 volumio volumio[1223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:33:22 volumio volumio[1223]: TypeError: Cannot read property 'length' of undefined May 01 10:33:22 volumio volumio[1223]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 01 10:33:22 volumio volumio[1223]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 01 10:33:22 volumio volumio[1223]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 01 10:33:22 volumio volumio[1223]: at Parser.emit (events.js:315:20) May 01 10:33:22 volumio volumio[1223]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 01 10:33:22 volumio volumio[1223]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 01 10:33:22 volumio volumio[1223]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 01 10:33:22 volumio volumio[1223]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 01 10:33:22 volumio volumio[1223]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 01 10:33:22 volumio volumio[1223]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 01 10:33:22 volumio volumio[1223]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 01 10:33:22 volumio volumio[1223]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 01 10:33:22 volumio volumio[1223]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 01 10:33:22 volumio volumio[1223]: at IncomingMessage.emit (events.js:327:22) May 01 10:33:22 volumio volumio[1223]: at endReadableNT (internal/streams/readable.js:1327:12) May 01 10:33:22 volumio volumio[1223]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 01 10:33:22 volumio volumio[1223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:33:22 volumio sudo[28688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 10:32 May 01 10:33:22 volumio sudo[28688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:22 volumio sudo[28688]: pam_unix(sudo:session): session closed for user root May 01 10:33:22 volumio volumio-remote-updater[701]: [2025-05-01 10:33:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 01 10:33:22 volumio volumio-remote-updater[701]: [2025-05-01 10:33:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 01 10:33:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 01 10:33:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 01 10:33:22 volumio systemd[1]: Started dynamicswap service. May 01 10:33:22 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:33:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 01 10:33:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 01 10:33:22 volumio systemd[1]: Started dynamicswap service. May 01 10:33:22 volumio systemd[1]: Stopped Volumio Backend Module. May 01 10:33:22 volumio systemd[1]: Started Volumio Backend Module. May 01 10:33:22 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:33:24 volumio volumio[28702]: info: ------------------------------------------- May 01 10:33:24 volumio volumio[28702]: info: ----- Volumio3 ---- May 01 10:33:24 volumio volumio[28702]: info: ------------------------------------------- May 01 10:33:24 volumio volumio[28702]: info: ----- System startup ---- May 01 10:33:24 volumio volumio[28702]: info: ------------------------------------------- May 01 10:33:24 volumio volumio[28702]: info: MYVOLUMIO Environment detected May 01 10:33:24 volumio volumio[28702]: info: Plugin folders cleanup May 01 10:33:24 volumio volumio[28702]: info: Scanning into folder /volumio/app/plugins/ May 01 10:33:24 volumio volumio[28702]: info: Scanning category audio_interface May 01 10:33:24 volumio volumio[28702]: info: Scanning category miscellanea May 01 10:33:24 volumio volumio[28702]: info: Scanning category music_service May 01 10:33:24 volumio volumio[28702]: info: Scanning category plugins.json May 01 10:33:24 volumio volumio[28702]: info: Scanning category system_controller May 01 10:33:24 volumio volumio[28702]: info: Scanning category user_interface May 01 10:33:24 volumio volumio[28702]: info: Scanning into folder /data/plugins/ May 01 10:33:24 volumio volumio[28702]: info: Scanning category system_hardware May 01 10:33:24 volumio volumio[28702]: info: Scanning category user_interface May 01 10:33:24 volumio volumio[28702]: info: Plugin folders cleanup completed May 01 10:33:24 volumio volumio[28702]: info: ------------------------------------------- May 01 10:33:24 volumio volumio[28702]: info: ----- Core plugins startup ---- May 01 10:33:24 volumio volumio[28702]: info: ------------------------------------------- May 01 10:33:24 volumio volumio[28702]: info: Loading plugins from folder /volumio/app/plugins/ May 01 10:33:24 volumio volumio[28702]: info: Adding plugin upnp to MyMusic Plugins May 01 10:33:24 volumio volumio[28702]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 10:33:24 volumio volumio[28702]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 10:33:24 volumio volumio[28702]: info: Loading plugins from folder /data/plugins/ May 01 10:33:24 volumio volumio[28702]: info: Loading plugin "system"... May 01 10:33:24 volumio volumio[28702]: info: Loading plugin "appearance"... May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "network"... May 01 10:33:25 volumio volumio[28702]: info: Refreshing Cached IP Addresses May 01 10:33:25 volumio sudo[28733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 10:33:25 volumio sudo[28733]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:25 volumio sudo[28735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 10:33:25 volumio sudo[28735]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:25 volumio sudo[28733]: pam_unix(sudo:session): session closed for user root May 01 10:33:25 volumio sudo[28735]: pam_unix(sudo:session): session closed for user root May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "services"... May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "alsa_controller"... May 01 10:33:25 volumio sudo[28744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 10:33:25 volumio sudo[28744]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:25 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "wizard"... May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "networkfs"... May 01 10:33:25 volumio volumio[28702]: info: Starting Udev Watcher for removable devices May 01 10:33:25 volumio sudo[28761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.31.52:/AUDIO-MUSIC /mnt/NAS/iconBIT May 01 10:33:25 volumio sudo[28761]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:25 volumio volumio[28702]: info: Ignoring mount for partition: boot May 01 10:33:25 volumio volumio[28702]: info: Ignoring mount for partition: volumio May 01 10:33:25 volumio volumio[28702]: info: Ignoring mount for partition: volumio_data May 01 10:33:25 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "volumio_command_line_client"... May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "upnp"... May 01 10:33:25 volumio volumio[28702]: info: [1746084805621] Starting Upmpd Daemon May 01 10:33:25 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "my_music"... May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "mpd"... May 01 10:33:25 volumio volumio[28702]: info: Loading plugin "upnp_browser"... May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "alarm-clock"... May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "airplay_emulation"... May 01 10:33:26 volumio volumio[28702]: info: Starting Shairport Sync May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "last_100"... May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "webradio"... May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "i2s_dacs"... May 01 10:33:26 volumio volumio[28702]: info: I2S DAC not set, start Auto-detection May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "volumiodiscovery"... May 01 10:33:26 volumio volumio[28702]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:33:26 volumio volumio[28702]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:26 volumio volumio[28702]: *** WARNING *** For more information see May 01 10:33:26 volumio node[28702]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:33:26 volumio volumio[28702]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:33:26 volumio volumio[28702]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:26 volumio volumio[28702]: *** WARNING *** For more information see May 01 10:33:26 volumio node[28702]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:26 volumio node[28702]: *** WARNING *** For more information see May 01 10:33:26 volumio node[28702]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:33:26 volumio node[28702]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:26 volumio node[28702]: *** WARNING *** For more information see May 01 10:33:26 volumio volumio[28702]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 10:33:26 volumio volumio[28702]: info: Discovery: Started advertising with name: Volumio May 01 10:33:26 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "outputs"... May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "albumart"... May 01 10:33:26 volumio volumio[28702]: info: Plugin example_plugin is not enabled May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "inputs"... May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "updater_comm"... May 01 10:33:26 volumio volumio[28702]: info: Plugin mpdemulation is not enabled May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "rest_api"... May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "websocket"... May 01 10:33:26 volumio volumio[28702]: info: Starting Socket.io Server version 2.3.0 May 01 10:33:26 volumio volumio[28702]: info: Loading plugin "touch_display"... May 01 10:33:26 volumio volumio[28702]: Forking 3 albumart workers May 01 10:33:26 volumio sudo[28744]: pam_unix(sudo:session): session closed for user root May 01 10:33:27 volumio volumio[28702]: info: Applying required configuration parameters for plugin touch_display May 01 10:33:27 volumio volumio[28702]: info: Loading i18n strings for locale ru May 01 10:33:27 volumio volumio[28702]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 01 10:33:27 volumio volumio[28702]: Updating browse sources language May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:33:27 volumio volumio[28702]: Starting albumart workers May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::initPlayerControls May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:27 volumio volumio[28702]: Express server listening on port 3000 May 01 10:33:27 volumio volumio[28702]: [Metrics] WebUI: 3s 703.67ms May 01 10:33:27 volumio volumio[28702]: info: CoreStateMachine::resetVolumioState May 01 10:33:27 volumio volumio[28702]: info: CoreStateMachine::getcurrentVolume May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:33:27 volumio volumio[28702]: Starting albumart workers May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:33:27 volumio volumio[28702]: info: Volumio Network Manager: Network status updated: 3 May 01 10:33:27 volumio volumio[28702]: Starting albumart workers May 01 10:33:27 volumio volumio[28702]: Unable to parse: May 01 10:33:27 volumio volumio[28702]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:27 volumio volumio[28702]: Capabilities: pswitch May 01 10:33:27 volumio volumio[28702]: Playback channels: Front Left - Front Right May 01 10:33:27 volumio volumio[28702]: Mono: May 01 10:33:27 volumio volumio[28702]: Front Left: Playback [on] May 01 10:33:27 volumio volumio[28702]: Front Right: Playback [on] May 01 10:33:27 volumio volumio[28702]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 01 10:33:27 volumio volumio-remote-updater[701]: [2025-05-01 10:33:27] [connect] Successful connection May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 May 01 10:33:27 volumio volumio[28702]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 01 10:33:27 volumio volumio[28702]: Unable to parse: May 01 10:33:27 volumio volumio[28702]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:27 volumio volumio[28702]: Capabilities: pswitch May 01 10:33:27 volumio volumio[28702]: Playback channels: Front Left - Front Right May 01 10:33:27 volumio volumio[28702]: Mono: May 01 10:33:27 volumio volumio[28702]: Front Left: Playback [on] May 01 10:33:27 volumio volumio[28702]: Front Right: Playback [on] May 01 10:33:27 volumio volumio[28702]: info: VolumeController:: Volume=undefined Mute =false May 01 10:33:27 volumio volumio[28702]: info: CoreStateMachine::pushState May 01 10:33:27 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::volumioPushState May 01 10:33:27 volumio volumio[28702]: info: CoreStateMachine::updateTrackBlock May 01 10:33:27 volumio volumio[28702]: info: CorePlayQueue::getTrackBlock May 01 10:33:27 volumio volumio[28702]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:33:27 volumio volumio[28702]: info: Reloading queue from file May 01 10:33:28 volumio volumio-remote-updater[701]: [2025-05-01 10:33:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746084807 101 May 01 10:33:28 volumio volumio[28702]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 10 May 01 10:33:28 volumio volumio[28702]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 May 01 10:33:28 volumio volumio[28702]: info: CoreStateMachine::setRepeat false single undefined May 01 10:33:28 volumio volumio[28702]: info: CoreStateMachine::pushState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioPushState May 01 10:33:28 volumio volumio[28702]: info: CoreStateMachine::setRandom null May 01 10:33:28 volumio volumio[28702]: info: CoreStateMachine::pushState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioPushState May 01 10:33:28 volumio volumio[28702]: info: Setting Device type: Raspberry PI May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 01 10:33:28 volumio volumio[28702]: Unable to parse: May 01 10:33:28 volumio volumio[28702]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:28 volumio volumio[28702]: Capabilities: pswitch May 01 10:33:28 volumio volumio[28702]: Playback channels: Front Left - Front Right May 01 10:33:28 volumio volumio[28702]: Mono: May 01 10:33:28 volumio volumio[28702]: Front Left: Playback [on] May 01 10:33:28 volumio volumio[28702]: Front Right: Playback [on] May 01 10:33:28 volumio volumio[28702]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:33:28 volumio volumio[28702]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 01 10:33:28 volumio volumio[28702]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 May 01 10:33:28 volumio volumio[28702]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 01 10:33:28 volumio volumio[28702]: info: Received Get System Info May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:33:28 volumio volumio[28702]: info: Discovery: Getting this device information May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:33:28 volumio volumio[28702]: info: Received Get System Info May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:33:28 volumio volumio[28702]: info: Discovery: Getting this device information May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: Listing playlists May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetQueue May 01 10:33:28 volumio volumio[28702]: info: CoreStateMachine::getQueue May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getQueue May 01 10:33:28 volumio volumio[28702]: Unable to parse: May 01 10:33:28 volumio volumio[28702]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:28 volumio volumio[28702]: Capabilities: pswitch May 01 10:33:28 volumio volumio[28702]: Playback channels: Front Left - Front Right May 01 10:33:28 volumio volumio[28702]: Mono: May 01 10:33:28 volumio volumio[28702]: Front Left: Playback [on] May 01 10:33:28 volumio volumio[28702]: Front Right: Playback [on] May 01 10:33:28 volumio volumio[28702]: info: VolumeController:: Volume=undefined Mute =false May 01 10:33:28 volumio volumio[28702]: info: CoreStateMachine::pushState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioPushState May 01 10:33:28 volumio volumio[28702]: info: Discovery: adding 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:33:28 volumio volumio[28702]: info: Discovery: Found device Volumio May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: Discovery: this is already registered, 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:33:28 volumio volumio[28702]: info: Discovery: Found device Volumio May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:33:28 volumio volumio[28702]: info: Received Get System Info May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:33:28 volumio volumio[28702]: info: Discovery: Getting this device information May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetState May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getTrack 0 May 01 10:33:28 volumio volumio[28702]: info: Listing playlists May 01 10:33:28 volumio volumio[28702]: info: CoreCommandRouter::volumioGetQueue May 01 10:33:28 volumio volumio[28702]: info: CoreStateMachine::getQueue May 01 10:33:28 volumio volumio[28702]: info: CorePlayQueue::getQueue May 01 10:33:30 volumio volumio[28702]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:33:30 volumio volumio[28702]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:33:30 volumio volumio[28702]: TypeError: Cannot read property 'length' of undefined May 01 10:33:30 volumio volumio[28702]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 01 10:33:30 volumio volumio[28702]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 01 10:33:30 volumio volumio[28702]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 01 10:33:30 volumio volumio[28702]: at Parser.emit (events.js:315:20) May 01 10:33:30 volumio volumio[28702]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 01 10:33:30 volumio volumio[28702]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 01 10:33:30 volumio volumio[28702]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 01 10:33:30 volumio volumio[28702]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 01 10:33:30 volumio volumio[28702]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 01 10:33:30 volumio volumio[28702]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 01 10:33:30 volumio volumio[28702]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 01 10:33:30 volumio volumio[28702]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 01 10:33:30 volumio volumio[28702]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 01 10:33:30 volumio volumio[28702]: at IncomingMessage.emit (events.js:327:22) May 01 10:33:30 volumio volumio[28702]: at endReadableNT (internal/streams/readable.js:1327:12) May 01 10:33:30 volumio volumio[28702]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 01 10:33:30 volumio volumio[28702]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:33:30 volumio sudo[28845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 10:32 May 01 10:33:30 volumio sudo[28845]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:30 volumio sudo[28845]: pam_unix(sudo:session): session closed for user root May 01 10:33:30 volumio volumio-remote-updater[701]: [2025-05-01 10:33:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 01 10:33:30 volumio volumio-remote-updater[701]: [2025-05-01 10:33:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 01 10:33:30 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 01 10:33:30 volumio sudo[28761]: pam_unix(sudo:session): session closed for user root May 01 10:33:30 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 01 10:33:30 volumio systemd[1]: Started dynamicswap service. May 01 10:33:30 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:33:31 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 01 10:33:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 01 10:33:31 volumio systemd[1]: Started dynamicswap service. May 01 10:33:31 volumio systemd[1]: Stopped Volumio Backend Module. May 01 10:33:31 volumio systemd[1]: Started Volumio Backend Module. May 01 10:33:31 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:33:32 volumio volumio[28858]: info: ------------------------------------------- May 01 10:33:32 volumio volumio[28858]: info: ----- Volumio3 ---- May 01 10:33:32 volumio volumio[28858]: info: ------------------------------------------- May 01 10:33:32 volumio volumio[28858]: info: ----- System startup ---- May 01 10:33:32 volumio volumio[28858]: info: ------------------------------------------- May 01 10:33:32 volumio volumio[28858]: info: MYVOLUMIO Environment detected May 01 10:33:32 volumio volumio[28858]: info: Plugin folders cleanup May 01 10:33:32 volumio volumio[28858]: info: Scanning into folder /volumio/app/plugins/ May 01 10:33:32 volumio volumio[28858]: info: Scanning category audio_interface May 01 10:33:32 volumio volumio[28858]: info: Scanning category miscellanea May 01 10:33:32 volumio volumio[28858]: info: Scanning category music_service May 01 10:33:32 volumio volumio[28858]: info: Scanning category plugins.json May 01 10:33:32 volumio volumio[28858]: info: Scanning category system_controller May 01 10:33:32 volumio volumio[28858]: info: Scanning category user_interface May 01 10:33:32 volumio volumio[28858]: info: Scanning into folder /data/plugins/ May 01 10:33:32 volumio volumio[28858]: info: Scanning category user_interface May 01 10:33:32 volumio volumio[28858]: info: Plugin folders cleanup completed May 01 10:33:32 volumio volumio[28858]: info: ------------------------------------------- May 01 10:33:32 volumio volumio[28858]: info: ----- Core plugins startup ---- May 01 10:33:32 volumio volumio[28858]: info: ------------------------------------------- May 01 10:33:32 volumio volumio[28858]: info: Loading plugins from folder /volumio/app/plugins/ May 01 10:33:32 volumio volumio[28858]: info: Adding plugin upnp to MyMusic Plugins May 01 10:33:32 volumio volumio[28858]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 10:33:32 volumio volumio[28858]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 10:33:32 volumio volumio[28858]: info: Loading plugins from folder /data/plugins/ May 01 10:33:32 volumio volumio[28858]: info: Loading plugin "system"... May 01 10:33:32 volumio volumio[28858]: info: Loading plugin "appearance"... May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "network"... May 01 10:33:33 volumio volumio[28858]: info: Refreshing Cached IP Addresses May 01 10:33:33 volumio sudo[28884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 10:33:33 volumio sudo[28884]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:33 volumio sudo[28884]: pam_unix(sudo:session): session closed for user root May 01 10:33:33 volumio sudo[28886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 10:33:33 volumio sudo[28886]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:33 volumio sudo[28886]: pam_unix(sudo:session): session closed for user root May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "services"... May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "alsa_controller"... May 01 10:33:33 volumio sudo[28899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 10:33:33 volumio sudo[28899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:33 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "wizard"... May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "networkfs"... May 01 10:33:33 volumio volumio[28858]: info: Starting Udev Watcher for removable devices May 01 10:33:33 volumio sudo[28912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.31.52:/AUDIO-MUSIC /mnt/NAS/iconBIT May 01 10:33:33 volumio sudo[28912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:33 volumio volumio[28858]: info: Ignoring mount for partition: boot May 01 10:33:33 volumio volumio[28858]: info: Ignoring mount for partition: volumio May 01 10:33:33 volumio volumio[28858]: info: Ignoring mount for partition: volumio_data May 01 10:33:33 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "volumio_command_line_client"... May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "upnp"... May 01 10:33:33 volumio volumio[28858]: info: [1746084813849] Starting Upmpd Daemon May 01 10:33:33 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "my_music"... May 01 10:33:33 volumio volumio[28858]: info: Loading plugin "mpd"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "upnp_browser"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "alarm-clock"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "airplay_emulation"... May 01 10:33:34 volumio volumio[28858]: info: Starting Shairport Sync May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "last_100"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "webradio"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "i2s_dacs"... May 01 10:33:34 volumio volumio[28858]: info: I2S DAC not set, start Auto-detection May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "volumiodiscovery"... May 01 10:33:34 volumio volumio[28858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:33:34 volumio volumio[28858]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:34 volumio volumio[28858]: *** WARNING *** For more information see May 01 10:33:34 volumio volumio[28858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:33:34 volumio volumio[28858]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:34 volumio volumio[28858]: *** WARNING *** For more information see May 01 10:33:34 volumio node[28858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:33:34 volumio node[28858]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:34 volumio node[28858]: *** WARNING *** For more information see May 01 10:33:34 volumio node[28858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:33:34 volumio node[28858]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:33:34 volumio node[28858]: *** WARNING *** For more information see May 01 10:33:34 volumio volumio[28858]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 10:33:34 volumio volumio[28858]: info: Discovery: Started advertising with name: Volumio May 01 10:33:34 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "outputs"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "albumart"... May 01 10:33:34 volumio volumio[28858]: info: Plugin example_plugin is not enabled May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "inputs"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "updater_comm"... May 01 10:33:34 volumio volumio[28858]: info: Plugin mpdemulation is not enabled May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "rest_api"... May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "websocket"... May 01 10:33:34 volumio volumio[28858]: info: Starting Socket.io Server version 2.3.0 May 01 10:33:34 volumio volumio[28858]: info: Loading plugin "touch_display"... May 01 10:33:34 volumio volumio[28858]: Forking 3 albumart workers May 01 10:33:35 volumio volumio[28858]: info: Applying required configuration parameters for plugin touch_display May 01 10:33:35 volumio sudo[28899]: pam_unix(sudo:session): session closed for user root May 01 10:33:35 volumio volumio[28858]: info: Loading i18n strings for locale ru May 01 10:33:35 volumio volumio[28858]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 01 10:33:35 volumio volumio[28858]: Updating browse sources language May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::initPlayerControls May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:33:35 volumio volumio[28858]: Express server listening on port 3000 May 01 10:33:35 volumio volumio[28858]: [Metrics] WebUI: 3s 390.24ms May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::resetVolumioState May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::getcurrentVolume May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:33:35 volumio volumio[28858]: info: Volumio Network Manager: Network status updated: 3 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 May 01 10:33:35 volumio volumio[28858]: Unable to parse: May 01 10:33:35 volumio volumio[28858]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:35 volumio volumio[28858]: Capabilities: pswitch May 01 10:33:35 volumio volumio[28858]: Playback channels: Front Left - Front Right May 01 10:33:35 volumio volumio[28858]: Mono: May 01 10:33:35 volumio volumio[28858]: Front Left: Playback [on] May 01 10:33:35 volumio volumio[28858]: Front Right: Playback [on] May 01 10:33:35 volumio volumio[28858]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 01 10:33:35 volumio volumio[28858]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 May 01 10:33:35 volumio volumio[28858]: Starting albumart workers May 01 10:33:35 volumio volumio[28858]: info: Reloading queue from file May 01 10:33:35 volumio volumio[28858]: Starting albumart workers May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::setRepeat false single undefined May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::pushState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioPushState May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::setRandom null May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::pushState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioPushState May 01 10:33:35 volumio volumio[28858]: info: Setting Device type: Raspberry PI May 01 10:33:35 volumio volumio[28858]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 01 10:33:35 volumio volumio[28858]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 May 01 10:33:35 volumio volumio[28858]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 01 10:33:35 volumio volumio-remote-updater[701]: [2025-05-01 10:33:35] [connect] Successful connection May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: Unable to parse: May 01 10:33:35 volumio volumio[28858]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:35 volumio volumio[28858]: Capabilities: pswitch May 01 10:33:35 volumio volumio[28858]: Playback channels: Front Left - Front Right May 01 10:33:35 volumio volumio[28858]: Mono: May 01 10:33:35 volumio volumio[28858]: Front Left: Playback [on] May 01 10:33:35 volumio volumio[28858]: Front Right: Playback [on] May 01 10:33:35 volumio volumio[28858]: info: VolumeController:: Volume=undefined Mute =false May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::pushState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioPushState May 01 10:33:35 volumio volumio[28858]: info: CoreStateMachine::updateTrackBlock May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrackBlock May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:33:35 volumio volumio[28858]: Starting albumart workers May 01 10:33:35 volumio volumio[28858]: info: Received Get System Info May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:33:35 volumio volumio[28858]: info: Discovery: Getting this device information May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:33:35 volumio volumio[28858]: info: Received Get System Info May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:33:35 volumio volumio[28858]: info: Discovery: Getting this device information May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:33:35 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:35 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:35 volumio volumio[28858]: info: Listing playlists May 01 10:33:36 volumio volumio[28858]: info: CoreCommandRouter::volumioGetQueue May 01 10:33:36 volumio volumio[28858]: info: CoreStateMachine::getQueue May 01 10:33:36 volumio volumio[28858]: info: CorePlayQueue::getQueue May 01 10:33:36 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:36 volumio volumio-remote-updater[701]: [2025-05-01 10:33:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746084815 101 May 01 10:33:36 volumio volumio[28858]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 12 May 01 10:33:36 volumio volumio[28858]: Unable to parse: May 01 10:33:36 volumio volumio[28858]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:36 volumio volumio[28858]: Capabilities: pswitch May 01 10:33:36 volumio volumio[28858]: Playback channels: Front Left - Front Right May 01 10:33:36 volumio volumio[28858]: Mono: May 01 10:33:36 volumio volumio[28858]: Front Left: Playback [on] May 01 10:33:36 volumio volumio[28858]: Front Right: Playback [on] May 01 10:33:36 volumio volumio[28858]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:33:36 volumio volumio[28858]: info: Discovery: adding 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:33:36 volumio volumio[28858]: info: Discovery: Found device Volumio May 01 10:33:36 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:36 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:36 volumio volumio[28858]: Unable to parse: May 01 10:33:36 volumio volumio[28858]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:33:36 volumio volumio[28858]: Capabilities: pswitch May 01 10:33:36 volumio volumio[28858]: Playback channels: Front Left - Front Right May 01 10:33:36 volumio volumio[28858]: Mono: May 01 10:33:36 volumio volumio[28858]: Front Left: Playback [on] May 01 10:33:36 volumio volumio[28858]: Front Right: Playback [on] May 01 10:33:36 volumio volumio[28858]: info: VolumeController:: Volume=undefined Mute =false May 01 10:33:36 volumio volumio[28858]: info: CoreStateMachine::pushState May 01 10:33:36 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:36 volumio volumio[28858]: info: CoreCommandRouter::volumioPushState May 01 10:33:36 volumio volumio[28858]: info: Discovery: this is already registered, 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:33:36 volumio volumio[28858]: info: Discovery: Found device Volumio May 01 10:33:36 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:36 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:36 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:36 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:37 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:37 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:38 volumio volumio[28858]: info: Preload queue cleared May 01 10:33:38 volumio volumio[28858]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::ClearQueue May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::stop May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 10:33:38 volumio volumio[28858]: info: CorePlayQueue::clearPlayQueue May 01 10:33:38 volumio volumio[28858]: info: CorePlayQueue::saveQueue May 01 10:33:38 volumio volumio[28858]: info: CoreCommandRouter::volumioPushQueue May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::addQueueItems May 01 10:33:38 volumio volumio[28858]: info: CorePlayQueue::addQueueItems May 01 10:33:38 volumio volumio[28858]: info: Preload queue cleared May 01 10:33:38 volumio volumio[28858]: info: Adding Item to queue: upnp/folder/http://192.168.31.163:49200/web/cds_control@1 May 01 10:33:38 volumio volumio[28858]: info: Exploding uri upnp/folder/http://192.168.31.163:49200/web/cds_control@1 in service upnp_browser May 01 10:33:38 volumio volumio[28858]: info: CoreCommandRouter::volumioPushQueue May 01 10:33:38 volumio volumio[28858]: info: CorePlayQueue::saveQueue May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::updateTrackBlock May 01 10:33:38 volumio volumio[28858]: info: CorePlayQueue::getTrackBlock May 01 10:33:38 volumio volumio[28858]: info: CoreCommandRouter::volumioPlay May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::play index 0 May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::stop May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::play index undefined May 01 10:33:38 volumio volumio[28858]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 10:33:38 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:38 volumio volumio[28858]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 May 01 10:33:38 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:39 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:33:39 volumio volumio[28858]: info: Received Get System Info May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:33:39 volumio volumio[28858]: info: Discovery: Getting this device information May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:39 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:39 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:33:39 volumio volumio[28858]: info: Listing playlists May 01 10:33:39 volumio volumio[28858]: info: CoreCommandRouter::volumioGetQueue May 01 10:33:39 volumio volumio[28858]: info: CoreStateMachine::getQueue May 01 10:33:39 volumio volumio[28858]: info: CorePlayQueue::getQueue May 01 10:33:40 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:33:40 volumio volumio[28858]: info: Preload queue cleared May 01 10:33:42 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:42 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:43 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:43 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:43 volumio sudo[29009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 10:33:43 volumio sudo[29009]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:43 volumio sudo[29009]: pam_unix(sudo:session): session closed for user root May 01 10:33:43 volumio sudo[29011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 10:33:43 volumio sudo[29011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:43 volumio sudo[29011]: pam_unix(sudo:session): session closed for user root May 01 10:33:43 volumio sudo[29015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 01 10:33:43 volumio sudo[29015]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:33:43 volumio sudo[29015]: pam_unix(sudo:session): session closed for user root May 01 10:33:43 volumio volumio[28858]: info: Upmpdcli Daemon Started May 01 10:33:44 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:44 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:45 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:45 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:33:45 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 10:33:45 volumio volumio[28858]: info: CURURI: music-library May 01 10:33:45 volumio volumio[28858]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined May 01 10:33:58 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:33:58 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:34:05 volumio volumio[28858]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion May 01 10:34:05 volumio volumio[28858]: info: Preparing to generate the ALSA configuration file May 01 10:34:05 volumio volumio[28858]: info: Asound.conf file unchanged, so no further update is needed May 01 10:34:05 volumio volumio[28858]: info: Output device has changed, restarting MPD May 01 10:34:05 volumio sudo[29121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 10:34:05 volumio sudo[29121]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio volumio[28858]: info: Output device has changed, restarting Shairport Sync May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:05 volumio sudo[29121]: pam_unix(sudo:session): session closed for user root May 01 10:34:05 volumio sudo[29124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 10:34:05 volumio sudo[29124]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio systemd[1]: Stopping Music Player Daemon... May 01 10:34:05 volumio volumio[28858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 10:34:05 volumio volumio[28858]: info: ___________ START PLUGINS ___________ May 01 10:34:05 volumio volumio[28858]: info: ControllerMpd::onStart: Initializing MPD May 01 10:34:05 volumio systemd[1]: mpd.service: Succeeded. May 01 10:34:05 volumio volumio[28858]: info: Creating MPD Configuration file May 01 10:34:05 volumio systemd[1]: Stopped Music Player Daemon. May 01 10:34:05 volumio sudo[29132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 10:34:05 volumio sudo[29132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 10:34:05 volumio volumio[28858]: info: [1746084845309] CoreMusicLibrary::Adding element Медиа-серверы May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:05 volumio sudo[29132]: pam_unix(sudo:session): session closed for user root May 01 10:34:05 volumio sudo[29134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 10:34:05 volumio sudo[29134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio systemd[1]: Starting Music Player Daemon... May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:05 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 01 10:34:05 volumio systemd[1]: mpd.service: Succeeded. May 01 10:34:05 volumio systemd[1]: Stopped Music Player Daemon. May 01 10:34:05 volumio systemd[1]: Starting Music Player Daemon... May 01 10:34:05 volumio volumio[28858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 10:34:05 volumio volumio[28858]: info: [1746084845410] CoreMusicLibrary::Adding element Last_100 May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 10:34:05 volumio volumio[28858]: info: [1746084845412] CoreMusicLibrary::Adding element Webradio May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:34:05 volumio volumio[28858]: info: Initializing BBC Radios May 01 10:34:05 volumio sudo[29141]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 01 10:34:05 volumio sudo[29141]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio sudo[29141]: pam_unix(sudo:session): session closed for user root May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: Loading i18n strings for locale ru May 01 10:34:05 volumio volumio[28858]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:34:05 volumio volumio[28858]: info: Volumio Calling Home May 01 10:34:05 volumio sudo[29161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 01 10:34:05 volumio sudo[29161]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio sudo[29163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 01 10:34:05 volumio sudo[29163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio sudo[29161]: pam_unix(sudo:session): session closed for user root May 01 10:34:05 volumio sudo[29167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 01 10:34:05 volumio sudo[29167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:05 volumio systemd[1]: Reloading. May 01 10:34:05 volumio volumio[28858]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' May 01 10:34:05 volumio volumio[28858]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 01 10:34:05 volumio volumio[28858]: info: MPD Permissions set May 01 10:34:05 volumio volumio[28858]: info: Volumio called home May 01 10:34:05 volumio volumio[28858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:05 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:06 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:06 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:06 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 01 10:34:06 volumio volumio[28858]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 01 10:34:06 volumio sudo[29191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 01 10:34:06 volumio sudo[29191]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:06 volumio volumio[28858]: info: Starting Shairport Sync May 01 10:34:06 volumio sudo[29191]: pam_unix(sudo:session): session closed for user root May 01 10:34:06 volumio volumio[28858]: info: Starting Shairport Sync May 01 10:34:06 volumio volumio[28858]: info: Starting Shairport Sync May 01 10:34:06 volumio sudo[29195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 10:34:06 volumio sudo[29195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:06 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:34:06 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:34:06 volumio sudo[29196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 10:34:06 volumio sudo[29199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 10:34:06 volumio sudo[29199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:06 volumio sudo[29196]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:06 volumio volumio[28858]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 01 10:34:06 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. May 01 10:34:06 volumio sudo[29167]: pam_unix(sudo:session): session closed for user root May 01 10:34:06 volumio volumio[28858]: info: touch_display: systemctl daemon-reload succeeded. May 01 10:34:06 volumio mpd[29148]: May 01 10:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 10:34:06 volumio systemd[1]: Reloading. May 01 10:34:06 volumio sudo[29203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 01 10:34:06 volumio sudo[29203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:06 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 01 10:34:06 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. May 01 10:34:07 volumio systemd[1]: Started Music Player Daemon. May 01 10:34:07 volumio sudo[29163]: pam_unix(sudo:session): session closed for user root May 01 10:34:07 volumio sudo[29134]: pam_unix(sudo:session): session closed for user root May 01 10:34:07 volumio volumio[28858]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 01 10:34:07 volumio sudo[29124]: pam_unix(sudo:session): session closed for user root May 01 10:34:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 10:34:07 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 10:34:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 10:34:07 volumio sudo[29203]: pam_unix(sudo:session): session closed for user root May 01 10:34:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 10:34:07 volumio sudo[29196]: pam_unix(sudo:session): session closed for user root May 01 10:34:07 volumio sudo[29199]: pam_unix(sudo:session): session closed for user root May 01 10:34:07 volumio sudo[29195]: pam_unix(sudo:session): session closed for user root May 01 10:34:07 volumio volumio[28858]: error: MPD error: The expression evaluated to a falsy value: May 01 10:34:07 volumio volumio[28858]: assert.ok(self.idling) May 01 10:34:07 volumio volumio[28858]: error: The expression evaluated to a falsy value: May 01 10:34:07 volumio volumio[28858]: assert.ok(self.idling) May 01 10:34:07 volumio volumio[28858]: info: Shairport-Sync Started May 01 10:34:07 volumio volumio[28858]: Error adding Membership: Error: addMembership EINVAL May 01 10:34:07 volumio volumio[28858]: info: Shairport-Sync Started May 01 10:34:07 volumio volumio[28858]: info: Shairport-Sync Started May 01 10:34:07 volumio volumio[28858]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 01 10:34:07 volumio volumio[28858]: info: touch_display: Volumio Kiosk started. May 01 10:34:07 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:34:07 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:34:07 volumio volumio[28858]: info: Completed starting Core Plugins May 01 10:34:07 volumio volumio[28858]: info: ------------------------------------------- May 01 10:34:07 volumio volumio[28858]: info: ----- MyVolumio plugins startup ---- May 01 10:34:07 volumio volumio[28858]: info: ------------------------------------------- May 01 10:34:07 volumio volumio[28858]: info: [MyVolumio PluginManager] Fetching plans data.... May 01 10:34:07 volumio volumio[28858]: info: MPD running with PID29148 May 01 10:34:07 volumio volumio[28858]: ,establishing connection May 01 10:34:07 volumio volumio[28858]: error: updateQueue error: null May 01 10:34:07 volumio volumio[28858]: info: CoreCommandRouter::volumioGetState May 01 10:34:07 volumio volumio[28858]: info: CorePlayQueue::getTrack 0 May 01 10:34:07 volumio volumio[28858]: error: updateQueue error: null May 01 10:34:07 volumio volumio[28858]: info: touch_display: X display number found: 0 May 01 10:34:07 volumio volumio[28858]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 01 10:34:07 volumio volumio[28858]: info: touch_display: X display number found: 0 May 01 10:34:07 volumio volumio[28858]: info: touch_display: Setting screensaver timeout to 6000 seconds. May 01 10:34:08 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:34:08 volumio volumio[28858]: info: Preload queue cleared May 01 10:34:11 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:34:11 volumio volumio[28858]: info: Preload queue cleared May 01 10:34:12 volumio volumio[28858]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 01 10:34:15 volumio volumio[28858]: info: Adding plugin bluetooth to MyMusic Plugins May 01 10:34:15 volumio volumio[28858]: info: Adding plugin multiroom to MyMusic Plugins May 01 10:34:15 volumio volumio[28858]: info: Adding plugin metavolumio to MyMusic Plugins May 01 10:34:15 volumio volumio[28858]: info: Adding plugin cd_controller to MyMusic Plugins May 01 10:34:15 volumio volumio[28858]: info: Adding plugin smart_inputs to MyMusic Plugins May 01 10:34:15 volumio volumio[28858]: info: Adding plugin tidalconnect to MyMusic Plugins May 01 10:34:15 volumio volumio[28858]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 01 10:34:16 volumio volumio[28858]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 01 10:34:16 volumio volumio[28858]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 01 10:34:16 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:16 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:16 volumio volumio[28858]: info: Starting MyVolumio Remote Streaming Endpoints May 01 10:34:16 volumio volumio[28858]: info: MyVolumio login type: Token May 01 10:34:16 volumio volumio[28858]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 01 10:34:16 volumio volumio[28858]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 01 10:34:17 volumio volumio[28858]: info: Starting Streaming Service Transparent Proxy May 01 10:34:17 volumio volumio[28858]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 01 10:34:17 volumio volumio[28858]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 01 10:34:17 volumio volumio[28858]: info: Streaming services startup May 01 10:34:17 volumio volumio[28858]: info: Starting Streaming Daemon May 01 10:34:17 volumio sudo[29263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 01 10:34:17 volumio sudo[29263]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:17 volumio volumio[28858]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 01 10:34:17 volumio sudo[29263]: pam_unix(sudo:session): session closed for user root May 01 10:34:17 volumio volumio[28858]: error: Cannot start Volumio Streaming Daemon May 01 10:34:17 volumio volumio[28858]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 01 10:34:17 volumio volumio[28858]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 01 10:34:17 volumio volumio[28858]: error: MyVolumio Custom Token format not valid, refreshing it May 01 10:34:17 volumio volumio[28858]: STREAMING PROXY: Starting server on port 3245 May 01 10:34:17 volumio volumio[28858]: Node JS runtime: 14 May 01 10:34:18 volumio volumio[28858]: info: MyVolumio login type: Token May 01 10:34:18 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:34:18 volumio volumio[28858]: info: Preload queue cleared May 01 10:34:18 volumio volumio[28858]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 01 10:34:19 volumio sudo[28912]: pam_unix(sudo:session): session closed for user root May 01 10:34:19 volumio volumio[28858]: info: Cannot mount NAS iconBIT at system boot, trial number 1 ,retrying in 5 seconds May 01 10:34:19 volumio volumio[28858]: info: MyVolumio token set successfully May 01 10:34:19 volumio volumio[28858]: info: MYVOLUMIO: Adding device May 01 10:34:19 volumio volumio[28858]: info: MYVOLUMIO: Evaluating Server May 01 10:34:20 volumio volumio[28858]: info: MyVolumio status changed May 01 10:34:20 volumio volumio[28858]: info: Streaming services startup May 01 10:34:20 volumio volumio[28858]: info: Starting Streaming Daemon May 01 10:34:20 volumio volumio[28858]: info: Removing browser output: myVolumio user plan is not superstar May 01 10:34:20 volumio volumio[28858]: info: Removing audio output: May 01 10:34:20 volumio volumio[28858]: info: Stoppping Tunnel 1 May 01 10:34:20 volumio sudo[29293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 01 10:34:20 volumio sudo[29293]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:20 volumio sudo[29296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 01 10:34:20 volumio sudo[29296]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:20 volumio sudo[29293]: pam_unix(sudo:session): session closed for user root May 01 10:34:20 volumio volumio[28858]: error: Cannot start Volumio Streaming Daemon May 01 10:34:20 volumio volumio[28858]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 01 10:34:20 volumio volumio[28858]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 01 10:34:20 volumio sudo[29296]: pam_unix(sudo:session): session closed for user root May 01 10:34:20 volumio volumio[28858]: info: Remote SSH Stopped May 01 10:34:20 volumio volumio[28858]: info: Setting Geolocation for MyVolumio to eu6 May 01 10:34:20 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:20 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:20 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:20 volumio volumio[28858]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:34:20 volumio volumio[28858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:20 volumio volumio[28858]: TypeError: Cannot read property 'length' of undefined May 01 10:34:20 volumio volumio[28858]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 01 10:34:20 volumio volumio[28858]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 01 10:34:20 volumio volumio[28858]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 01 10:34:20 volumio volumio[28858]: at Parser.emit (events.js:315:20) May 01 10:34:20 volumio volumio[28858]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 01 10:34:20 volumio volumio[28858]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 01 10:34:20 volumio volumio[28858]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 01 10:34:20 volumio volumio[28858]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 01 10:34:20 volumio volumio[28858]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 01 10:34:20 volumio volumio[28858]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 01 10:34:20 volumio volumio[28858]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 01 10:34:20 volumio volumio[28858]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 01 10:34:20 volumio volumio[28858]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 01 10:34:20 volumio volumio[28858]: at IncomingMessage.emit (events.js:327:22) May 01 10:34:20 volumio volumio[28858]: at endReadableNT (internal/streams/readable.js:1327:12) May 01 10:34:20 volumio volumio[28858]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 01 10:34:20 volumio volumio[28858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:20 volumio sudo[29307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 10:33 May 01 10:34:20 volumio sudo[29307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:20 volumio sudo[29307]: pam_unix(sudo:session): session closed for user root May 01 10:34:21 volumio volumio-remote-updater[701]: [2025-05-01 10:34:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 01 10:34:21 volumio volumio-remote-updater[701]: [2025-05-01 10:34:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 01 10:34:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 01 10:34:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 01 10:34:21 volumio systemd[1]: Started dynamicswap service. May 01 10:34:21 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:34:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 01 10:34:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. May 01 10:34:21 volumio systemd[1]: Started dynamicswap service. May 01 10:34:21 volumio systemd[1]: Stopped Volumio Backend Module. May 01 10:34:21 volumio systemd[1]: Started Volumio Backend Module. May 01 10:34:21 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:34:22 volumio volumio[29318]: info: ------------------------------------------- May 01 10:34:22 volumio volumio[29318]: info: ----- Volumio3 ---- May 01 10:34:22 volumio volumio[29318]: info: ------------------------------------------- May 01 10:34:22 volumio volumio[29318]: info: ----- System startup ---- May 01 10:34:22 volumio volumio[29318]: info: ------------------------------------------- May 01 10:34:23 volumio volumio[29318]: info: MYVOLUMIO Environment detected May 01 10:34:23 volumio volumio[29318]: info: Plugin folders cleanup May 01 10:34:23 volumio volumio[29318]: info: Scanning into folder /volumio/app/plugins/ May 01 10:34:23 volumio volumio[29318]: info: Scanning category audio_interface May 01 10:34:23 volumio volumio[29318]: info: Scanning category miscellanea May 01 10:34:23 volumio volumio[29318]: info: Scanning category music_service May 01 10:34:23 volumio volumio[29318]: info: Scanning category plugins.json May 01 10:34:23 volumio volumio[29318]: info: Scanning category system_controller May 01 10:34:23 volumio volumio[29318]: info: Scanning category user_interface May 01 10:34:23 volumio volumio[29318]: info: Scanning into folder /data/plugins/ May 01 10:34:23 volumio volumio[29318]: info: Scanning category user_interface May 01 10:34:23 volumio volumio[29318]: info: Plugin folders cleanup completed May 01 10:34:23 volumio volumio[29318]: info: ------------------------------------------- May 01 10:34:23 volumio volumio[29318]: info: ----- Core plugins startup ---- May 01 10:34:23 volumio volumio[29318]: info: ------------------------------------------- May 01 10:34:23 volumio volumio[29318]: info: Loading plugins from folder /volumio/app/plugins/ May 01 10:34:23 volumio volumio[29318]: info: Adding plugin upnp to MyMusic Plugins May 01 10:34:23 volumio volumio[29318]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 10:34:23 volumio volumio[29318]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 10:34:23 volumio volumio[29318]: info: Loading plugins from folder /data/plugins/ May 01 10:34:23 volumio volumio[29318]: info: Loading plugin "system"... May 01 10:34:23 volumio volumio[29318]: info: Loading plugin "appearance"... May 01 10:34:23 volumio volumio[29318]: info: Loading plugin "network"... May 01 10:34:23 volumio volumio[29318]: info: Refreshing Cached IP Addresses May 01 10:34:23 volumio sudo[29347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 10:34:23 volumio sudo[29347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:23 volumio sudo[29347]: pam_unix(sudo:session): session closed for user root May 01 10:34:23 volumio sudo[29349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 10:34:23 volumio volumio[29318]: info: Loading plugin "services"... May 01 10:34:23 volumio sudo[29349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:23 volumio volumio[29318]: info: Loading plugin "alsa_controller"... May 01 10:34:23 volumio sudo[29357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 10:34:23 volumio sudo[29349]: pam_unix(sudo:session): session closed for user root May 01 10:34:23 volumio sudo[29357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:24 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "wizard"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "networkfs"... May 01 10:34:24 volumio volumio[29318]: info: Starting Udev Watcher for removable devices May 01 10:34:24 volumio sudo[29375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.31.52:/AUDIO-MUSIC /mnt/NAS/iconBIT May 01 10:34:24 volumio sudo[29375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:24 volumio volumio[29318]: info: Ignoring mount for partition: boot May 01 10:34:24 volumio volumio[29318]: info: Ignoring mount for partition: volumio May 01 10:34:24 volumio volumio[29318]: info: Ignoring mount for partition: volumio_data May 01 10:34:24 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "volumio_command_line_client"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "upnp"... May 01 10:34:24 volumio volumio[29318]: info: [1746084864085] Starting Upmpd Daemon May 01 10:34:24 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "my_music"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "mpd"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "upnp_browser"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "alarm-clock"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "airplay_emulation"... May 01 10:34:24 volumio volumio[29318]: info: Starting Shairport Sync May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "last_100"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "webradio"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "i2s_dacs"... May 01 10:34:24 volumio volumio[29318]: info: I2S DAC not set, start Auto-detection May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "volumiodiscovery"... May 01 10:34:24 volumio volumio[29318]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:34:24 volumio volumio[29318]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:24 volumio volumio[29318]: *** WARNING *** For more information see May 01 10:34:24 volumio volumio[29318]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:34:24 volumio volumio[29318]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:24 volumio volumio[29318]: *** WARNING *** For more information see May 01 10:34:24 volumio node[29318]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:34:24 volumio node[29318]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:24 volumio node[29318]: *** WARNING *** For more information see May 01 10:34:24 volumio node[29318]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:34:24 volumio node[29318]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:24 volumio node[29318]: *** WARNING *** For more information see May 01 10:34:24 volumio volumio[29318]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 10:34:24 volumio volumio[29318]: info: Discovery: Started advertising with name: Volumio May 01 10:34:24 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "outputs"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "albumart"... May 01 10:34:24 volumio volumio[29318]: info: Plugin example_plugin is not enabled May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "inputs"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "updater_comm"... May 01 10:34:24 volumio volumio[29318]: info: Plugin mpdemulation is not enabled May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "rest_api"... May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "websocket"... May 01 10:34:24 volumio volumio[29318]: info: Starting Socket.io Server version 2.3.0 May 01 10:34:24 volumio volumio[29318]: info: Loading plugin "touch_display"... May 01 10:34:25 volumio volumio[29318]: Forking 3 albumart workers May 01 10:34:25 volumio sudo[29357]: pam_unix(sudo:session): session closed for user root May 01 10:34:25 volumio volumio[29318]: info: Applying required configuration parameters for plugin touch_display May 01 10:34:25 volumio volumio[29318]: info: Loading i18n strings for locale ru May 01 10:34:25 volumio volumio[29318]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 01 10:34:25 volumio volumio[29318]: Updating browse sources language May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::initPlayerControls May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:34:25 volumio volumio[29318]: Express server listening on port 3000 May 01 10:34:25 volumio volumio[29318]: [Metrics] WebUI: 3s 567.57ms May 01 10:34:25 volumio volumio[29318]: Starting albumart workers May 01 10:34:25 volumio volumio[29318]: info: CoreStateMachine::resetVolumioState May 01 10:34:25 volumio volumio[29318]: info: CoreStateMachine::getcurrentVolume May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:34:25 volumio volumio[29318]: Starting albumart workers May 01 10:34:25 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:26 volumio volumio[29318]: info: Volumio Network Manager: Network status updated: 3 May 01 10:34:26 volumio volumio-remote-updater[701]: [2025-05-01 10:34:26] [connect] Successful connection May 01 10:34:26 volumio volumio[29318]: Starting albumart workers May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 May 01 10:34:26 volumio volumio[29318]: Unable to parse: May 01 10:34:26 volumio volumio[29318]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:26 volumio volumio[29318]: Capabilities: pswitch May 01 10:34:26 volumio volumio[29318]: Playback channels: Front Left - Front Right May 01 10:34:26 volumio volumio[29318]: Mono: May 01 10:34:26 volumio volumio[29318]: Front Left: Playback [on] May 01 10:34:26 volumio volumio[29318]: Front Right: Playback [on] May 01 10:34:26 volumio volumio[29318]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 01 10:34:26 volumio volumio-remote-updater[701]: [2025-05-01 10:34:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746084866 101 May 01 10:34:26 volumio volumio[29318]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 14 May 01 10:34:26 volumio volumio[29318]: info: Reloading queue from file May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: Unable to parse: May 01 10:34:26 volumio volumio[29318]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:26 volumio volumio[29318]: Capabilities: pswitch May 01 10:34:26 volumio volumio[29318]: Playback channels: Front Left - Front Right May 01 10:34:26 volumio volumio[29318]: Mono: May 01 10:34:26 volumio volumio[29318]: Front Left: Playback [on] May 01 10:34:26 volumio volumio[29318]: Front Right: Playback [on] May 01 10:34:26 volumio volumio[29318]: info: VolumeController:: Volume=undefined Mute =false May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::pushState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioPushState May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::updateTrackBlock May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrackBlock May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::setRepeat false single undefined May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::pushState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioPushState May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::setRandom null May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::pushState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioPushState May 01 10:34:26 volumio volumio[29318]: info: Setting Device type: Raspberry PI May 01 10:34:26 volumio volumio[29318]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 01 10:34:26 volumio volumio[29318]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 May 01 10:34:26 volumio volumio[29318]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 01 10:34:26 volumio volumio[29318]: info: Received Get System Info May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:26 volumio volumio[29318]: info: Discovery: Getting this device information May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:34:26 volumio volumio[29318]: info: Received Get System Info May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:26 volumio volumio[29318]: info: Discovery: Getting this device information May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: Listing playlists May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetQueue May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::getQueue May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getQueue May 01 10:34:26 volumio volumio[29318]: Unable to parse: May 01 10:34:26 volumio volumio[29318]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:26 volumio volumio[29318]: Capabilities: pswitch May 01 10:34:26 volumio volumio[29318]: Playback channels: Front Left - Front Right May 01 10:34:26 volumio volumio[29318]: Mono: May 01 10:34:26 volumio volumio[29318]: Front Left: Playback [on] May 01 10:34:26 volumio volumio[29318]: Front Right: Playback [on] May 01 10:34:26 volumio volumio[29318]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:34:26 volumio volumio[29318]: Unable to parse: May 01 10:34:26 volumio volumio[29318]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:26 volumio volumio[29318]: Capabilities: pswitch May 01 10:34:26 volumio volumio[29318]: Playback channels: Front Left - Front Right May 01 10:34:26 volumio volumio[29318]: Mono: May 01 10:34:26 volumio volumio[29318]: Front Left: Playback [on] May 01 10:34:26 volumio volumio[29318]: Front Right: Playback [on] May 01 10:34:26 volumio volumio[29318]: info: VolumeController:: Volume=undefined Mute =false May 01 10:34:26 volumio volumio[29318]: info: CoreStateMachine::pushState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioPushState May 01 10:34:26 volumio volumio[29318]: info: Discovery: adding 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:34:26 volumio volumio[29318]: info: Discovery: Found device Volumio May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:26 volumio volumio[29318]: info: Discovery: this is already registered, 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:34:26 volumio volumio[29318]: info: Discovery: Found device Volumio May 01 10:34:26 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:26 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:28 volumio volumio[29318]: info: CoreCommandRouter::volumioPlay May 01 10:34:28 volumio volumio[29318]: info: CoreStateMachine::play index undefined May 01 10:34:28 volumio volumio[29318]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 10:34:28 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:28 volumio volumio[29318]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 May 01 10:34:28 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:29 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:34:29 volumio volumio[29318]: info: Received Get System Info May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:29 volumio volumio[29318]: info: Discovery: Getting this device information May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:29 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::volumioGetState May 01 10:34:29 volumio volumio[29318]: info: CorePlayQueue::getTrack 0 May 01 10:34:29 volumio volumio[29318]: info: Listing playlists May 01 10:34:29 volumio volumio[29318]: info: CoreCommandRouter::volumioGetQueue May 01 10:34:29 volumio volumio[29318]: info: CoreStateMachine::getQueue May 01 10:34:29 volumio volumio[29318]: info: CorePlayQueue::getQueue May 01 10:34:32 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:32 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:33 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:33 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:34 volumio sudo[29470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 10:34:34 volumio sudo[29470]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:34 volumio sudo[29470]: pam_unix(sudo:session): session closed for user root May 01 10:34:34 volumio sudo[29473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 10:34:34 volumio sudo[29473]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:34 volumio sudo[29473]: pam_unix(sudo:session): session closed for user root May 01 10:34:34 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:34 volumio sudo[29476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 01 10:34:34 volumio sudo[29476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:34 volumio sudo[29476]: pam_unix(sudo:session): session closed for user root May 01 10:34:34 volumio volumio[29318]: info: Upmpdcli Daemon Started May 01 10:34:34 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:35 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:35 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:36 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:36 volumio volumio[29318]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 01 10:34:36 volumio volumio[29318]: verbose: ControllerMpd::sendMpdCommand update May 01 10:34:36 volumio volumio[29318]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:36 volumio volumio[29318]: TypeError: Cannot read property 'then' of undefined May 01 10:34:36 volumio volumio[29318]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) May 01 10:34:36 volumio volumio[29318]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) May 01 10:34:36 volumio volumio[29318]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 01 10:34:36 volumio volumio[29318]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1091:26) May 01 10:34:36 volumio volumio[29318]: at Socket.emit (events.js:315:20) May 01 10:34:36 volumio volumio[29318]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 01 10:34:36 volumio volumio[29318]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 01 10:34:36 volumio volumio[29318]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:36 volumio sudo[29488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 10:33 May 01 10:34:36 volumio sudo[29488]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:36 volumio sudo[29488]: pam_unix(sudo:session): session closed for user root May 01 10:34:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 01 10:34:37 volumio volumio-remote-updater[701]: [2025-05-01 10:34:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 01 10:34:37 volumio volumio-remote-updater[701]: [2025-05-01 10:34:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 01 10:34:37 volumio sudo[29375]: pam_unix(sudo:session): session closed for user root May 01 10:34:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 01 10:34:37 volumio systemd[1]: Started dynamicswap service. May 01 10:34:37 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:34:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 01 10:34:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. May 01 10:34:37 volumio systemd[1]: Started dynamicswap service. May 01 10:34:37 volumio systemd[1]: Stopped Volumio Backend Module. May 01 10:34:37 volumio systemd[1]: Started Volumio Backend Module. May 01 10:34:37 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:34:38 volumio volumio[29503]: info: ------------------------------------------- May 01 10:34:38 volumio volumio[29503]: info: ----- Volumio3 ---- May 01 10:34:38 volumio volumio[29503]: info: ------------------------------------------- May 01 10:34:38 volumio volumio[29503]: info: ----- System startup ---- May 01 10:34:38 volumio volumio[29503]: info: ------------------------------------------- May 01 10:34:39 volumio volumio[29503]: info: MYVOLUMIO Environment detected May 01 10:34:39 volumio volumio[29503]: info: Plugin folders cleanup May 01 10:34:39 volumio volumio[29503]: info: Scanning into folder /volumio/app/plugins/ May 01 10:34:39 volumio volumio[29503]: info: Scanning category audio_interface May 01 10:34:39 volumio volumio[29503]: info: Scanning category miscellanea May 01 10:34:39 volumio volumio[29503]: info: Scanning category music_service May 01 10:34:39 volumio volumio[29503]: info: Scanning category plugins.json May 01 10:34:39 volumio volumio[29503]: info: Scanning category system_controller May 01 10:34:39 volumio volumio[29503]: info: Scanning category user_interface May 01 10:34:39 volumio volumio[29503]: info: Scanning into folder /data/plugins/ May 01 10:34:39 volumio volumio[29503]: info: Scanning category user_interface May 01 10:34:39 volumio volumio[29503]: info: Plugin folders cleanup completed May 01 10:34:39 volumio volumio[29503]: info: ------------------------------------------- May 01 10:34:39 volumio volumio[29503]: info: ----- Core plugins startup ---- May 01 10:34:39 volumio volumio[29503]: info: ------------------------------------------- May 01 10:34:39 volumio volumio[29503]: info: Loading plugins from folder /volumio/app/plugins/ May 01 10:34:39 volumio volumio[29503]: info: Adding plugin upnp to MyMusic Plugins May 01 10:34:39 volumio volumio[29503]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 10:34:39 volumio volumio[29503]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 10:34:39 volumio volumio[29503]: info: Loading plugins from folder /data/plugins/ May 01 10:34:39 volumio volumio[29503]: info: Loading plugin "system"... May 01 10:34:39 volumio volumio[29503]: info: Loading plugin "appearance"... May 01 10:34:39 volumio volumio[29503]: info: Loading plugin "network"... May 01 10:34:39 volumio volumio[29503]: info: Refreshing Cached IP Addresses May 01 10:34:39 volumio sudo[29530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 10:34:39 volumio sudo[29530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:39 volumio sudo[29530]: pam_unix(sudo:session): session closed for user root May 01 10:34:39 volumio sudo[29532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 10:34:39 volumio sudo[29532]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:39 volumio sudo[29532]: pam_unix(sudo:session): session closed for user root May 01 10:34:39 volumio volumio[29503]: info: Loading plugin "services"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "alsa_controller"... May 01 10:34:40 volumio sudo[29541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 10:34:40 volumio sudo[29541]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:40 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "wizard"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "networkfs"... May 01 10:34:40 volumio volumio[29503]: info: Starting Udev Watcher for removable devices May 01 10:34:40 volumio sudo[29558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.31.52:/AUDIO-MUSIC /mnt/NAS/iconBIT May 01 10:34:40 volumio sudo[29558]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:40 volumio volumio[29503]: info: Ignoring mount for partition: boot May 01 10:34:40 volumio volumio[29503]: info: Ignoring mount for partition: volumio May 01 10:34:40 volumio volumio[29503]: info: Ignoring mount for partition: volumio_data May 01 10:34:40 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "volumio_command_line_client"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "upnp"... May 01 10:34:40 volumio volumio[29503]: info: [1746084880164] Starting Upmpd Daemon May 01 10:34:40 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "my_music"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "mpd"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "upnp_browser"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "alarm-clock"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "airplay_emulation"... May 01 10:34:40 volumio volumio[29503]: info: Starting Shairport Sync May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "last_100"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "webradio"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "i2s_dacs"... May 01 10:34:40 volumio volumio[29503]: info: I2S DAC not set, start Auto-detection May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "volumiodiscovery"... May 01 10:34:40 volumio volumio[29503]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:34:40 volumio volumio[29503]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:40 volumio volumio[29503]: *** WARNING *** For more information see May 01 10:34:40 volumio node[29503]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:34:40 volumio volumio[29503]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:34:40 volumio volumio[29503]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:40 volumio volumio[29503]: *** WARNING *** For more information see May 01 10:34:40 volumio node[29503]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:40 volumio node[29503]: *** WARNING *** For more information see May 01 10:34:40 volumio node[29503]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:34:40 volumio node[29503]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:40 volumio node[29503]: *** WARNING *** For more information see May 01 10:34:40 volumio volumio[29503]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 10:34:40 volumio volumio[29503]: info: Discovery: Started advertising with name: Volumio May 01 10:34:40 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "outputs"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "albumart"... May 01 10:34:40 volumio volumio[29503]: info: Plugin example_plugin is not enabled May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "inputs"... May 01 10:34:40 volumio volumio[29503]: info: Loading plugin "updater_comm"... May 01 10:34:41 volumio volumio[29503]: info: Plugin mpdemulation is not enabled May 01 10:34:41 volumio volumio[29503]: info: Loading plugin "rest_api"... May 01 10:34:41 volumio volumio[29503]: info: Loading plugin "websocket"... May 01 10:34:41 volumio volumio[29503]: info: Starting Socket.io Server version 2.3.0 May 01 10:34:41 volumio volumio[29503]: info: Loading plugin "touch_display"... May 01 10:34:41 volumio volumio[29503]: Forking 3 albumart workers May 01 10:34:41 volumio volumio[29503]: info: Applying required configuration parameters for plugin touch_display May 01 10:34:41 volumio sudo[29541]: pam_unix(sudo:session): session closed for user root May 01 10:34:41 volumio volumio[29503]: info: Loading i18n strings for locale ru May 01 10:34:41 volumio volumio[29503]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 01 10:34:41 volumio volumio[29503]: Updating browse sources language May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::initPlayerControls May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:34:41 volumio volumio[29503]: Express server listening on port 3000 May 01 10:34:41 volumio volumio[29503]: [Metrics] WebUI: 3s 454.62ms May 01 10:34:41 volumio volumio[29503]: info: CoreStateMachine::resetVolumioState May 01 10:34:41 volumio volumio[29503]: info: CoreStateMachine::getcurrentVolume May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:34:41 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:41 volumio volumio[29503]: info: Volumio Network Manager: Network status updated: 3 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 01 10:34:41 volumio volumio[29503]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 May 01 10:34:41 volumio volumio[29503]: Unable to parse: May 01 10:34:41 volumio volumio[29503]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:41 volumio volumio[29503]: Capabilities: pswitch May 01 10:34:41 volumio volumio[29503]: Playback channels: Front Left - Front Right May 01 10:34:41 volumio volumio[29503]: Mono: May 01 10:34:41 volumio volumio[29503]: Front Left: Playback [on] May 01 10:34:41 volumio volumio[29503]: Front Right: Playback [on] May 01 10:34:41 volumio volumio[29503]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 01 10:34:42 volumio volumio[29503]: Starting albumart workers May 01 10:34:42 volumio volumio[29503]: Starting albumart workers May 01 10:34:42 volumio volumio[29503]: info: Reloading queue from file May 01 10:34:42 volumio volumio[29503]: info: Received Get System Info May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:42 volumio volumio[29503]: info: Discovery: Getting this device information May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:42 volumio volumio-remote-updater[701]: [2025-05-01 10:34:42] [connect] Successful connection May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:34:42 volumio volumio[29503]: info: Received Get System Info May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:42 volumio volumio[29503]: info: Discovery: Getting this device information May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: Listing playlists May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetQueue May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::getQueue May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getQueue May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:34:42 volumio volumio[29503]: Unable to parse: May 01 10:34:42 volumio volumio[29503]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:42 volumio volumio[29503]: Capabilities: pswitch May 01 10:34:42 volumio volumio[29503]: Playback channels: Front Left - Front Right May 01 10:34:42 volumio volumio[29503]: Mono: May 01 10:34:42 volumio volumio[29503]: Front Left: Playback [on] May 01 10:34:42 volumio volumio[29503]: Front Right: Playback [on] May 01 10:34:42 volumio volumio[29503]: info: VolumeController:: Volume=undefined Mute =false May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::pushState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioPushState May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::updateTrackBlock May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrackBlock May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::setRepeat false single undefined May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::pushState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioPushState May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::setRandom null May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::pushState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioPushState May 01 10:34:42 volumio volumio[29503]: info: Setting Device type: Raspberry PI May 01 10:34:42 volumio volumio[29503]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 01 10:34:42 volumio volumio[29503]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 May 01 10:34:42 volumio volumio[29503]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 01 10:34:42 volumio volumio[29503]: Starting albumart workers May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:34:42 volumio volumio[29503]: info: Received Get System Info May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:42 volumio volumio[29503]: info: Discovery: Getting this device information May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: Listing playlists May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetQueue May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::getQueue May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getQueue May 01 10:34:42 volumio volumio-remote-updater[701]: [2025-05-01 10:34:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746084882 101 May 01 10:34:42 volumio volumio[29503]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 11 May 01 10:34:42 volumio volumio[29503]: Unable to parse: May 01 10:34:42 volumio volumio[29503]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:42 volumio volumio[29503]: Capabilities: pswitch May 01 10:34:42 volumio volumio[29503]: Playback channels: Front Left - Front Right May 01 10:34:42 volumio volumio[29503]: Mono: May 01 10:34:42 volumio volumio[29503]: Front Left: Playback [on] May 01 10:34:42 volumio volumio[29503]: Front Right: Playback [on] May 01 10:34:42 volumio volumio[29503]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:34:42 volumio volumio[29503]: Unable to parse: May 01 10:34:42 volumio volumio[29503]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:42 volumio volumio[29503]: Capabilities: pswitch May 01 10:34:42 volumio volumio[29503]: Playback channels: Front Left - Front Right May 01 10:34:42 volumio volumio[29503]: Mono: May 01 10:34:42 volumio volumio[29503]: Front Left: Playback [on] May 01 10:34:42 volumio volumio[29503]: Front Right: Playback [on] May 01 10:34:42 volumio volumio[29503]: info: VolumeController:: Volume=undefined Mute =false May 01 10:34:42 volumio volumio[29503]: info: CoreStateMachine::pushState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioPushState May 01 10:34:42 volumio volumio[29503]: info: Discovery: adding 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:34:42 volumio volumio[29503]: info: Discovery: Found device Volumio May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:42 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:42 volumio volumio[29503]: info: Discovery: this is already registered, 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:34:42 volumio volumio[29503]: info: Discovery: Found device Volumio May 01 10:34:42 volumio volumio[29503]: info: CoreCommandRouter::volumioGetState May 01 10:34:43 volumio volumio[29503]: info: CorePlayQueue::getTrack 0 May 01 10:34:45 volumio volumio[29503]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 10:34:45 volumio volumio[29503]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:45 volumio volumio[29503]: TypeError: Cannot read property 'length' of undefined May 01 10:34:45 volumio volumio[29503]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 01 10:34:45 volumio volumio[29503]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 01 10:34:45 volumio volumio[29503]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 01 10:34:45 volumio volumio[29503]: at Parser.emit (events.js:315:20) May 01 10:34:45 volumio volumio[29503]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 01 10:34:45 volumio volumio[29503]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 01 10:34:45 volumio volumio[29503]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 01 10:34:45 volumio volumio[29503]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 01 10:34:45 volumio volumio[29503]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 01 10:34:45 volumio volumio[29503]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 01 10:34:45 volumio volumio[29503]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 01 10:34:45 volumio volumio[29503]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 01 10:34:45 volumio volumio[29503]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 01 10:34:45 volumio volumio[29503]: at IncomingMessage.emit (events.js:327:22) May 01 10:34:45 volumio volumio[29503]: at endReadableNT (internal/streams/readable.js:1327:12) May 01 10:34:45 volumio volumio[29503]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 01 10:34:45 volumio volumio[29503]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:45 volumio sudo[29644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 10:33 May 01 10:34:45 volumio sudo[29644]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:45 volumio sudo[29644]: pam_unix(sudo:session): session closed for user root May 01 10:34:46 volumio volumio-remote-updater[701]: [2025-05-01 10:34:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 01 10:34:46 volumio volumio-remote-updater[701]: [2025-05-01 10:34:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 01 10:34:46 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 01 10:34:46 volumio sudo[29558]: pam_unix(sudo:session): session closed for user root May 01 10:34:46 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 01 10:34:46 volumio systemd[1]: Started dynamicswap service. May 01 10:34:46 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:34:46 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 01 10:34:46 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. May 01 10:34:46 volumio systemd[1]: Stopped Volumio Backend Module. May 01 10:34:46 volumio systemd[1]: Started Volumio Backend Module. May 01 10:34:46 volumio systemd[1]: Started dynamicswap service. May 01 10:34:46 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 10:34:47 volumio volumio[29657]: info: ------------------------------------------- May 01 10:34:47 volumio volumio[29657]: info: ----- Volumio3 ---- May 01 10:34:47 volumio volumio[29657]: info: ------------------------------------------- May 01 10:34:47 volumio volumio[29657]: info: ----- System startup ---- May 01 10:34:47 volumio volumio[29657]: info: ------------------------------------------- May 01 10:34:48 volumio volumio[29657]: info: MYVOLUMIO Environment detected May 01 10:34:48 volumio volumio[29657]: info: Plugin folders cleanup May 01 10:34:48 volumio volumio[29657]: info: Scanning into folder /volumio/app/plugins/ May 01 10:34:48 volumio volumio[29657]: info: Scanning category audio_interface May 01 10:34:48 volumio volumio[29657]: info: Scanning category miscellanea May 01 10:34:48 volumio volumio[29657]: info: Scanning category music_service May 01 10:34:48 volumio volumio[29657]: info: Scanning category plugins.json May 01 10:34:48 volumio volumio[29657]: info: Scanning category system_controller May 01 10:34:48 volumio volumio[29657]: info: Scanning category user_interface May 01 10:34:48 volumio volumio[29657]: info: Scanning into folder /data/plugins/ May 01 10:34:48 volumio volumio[29657]: info: Scanning category user_interface May 01 10:34:48 volumio volumio[29657]: info: Plugin folders cleanup completed May 01 10:34:48 volumio volumio[29657]: info: ------------------------------------------- May 01 10:34:48 volumio volumio[29657]: info: ----- Core plugins startup ---- May 01 10:34:48 volumio volumio[29657]: info: ------------------------------------------- May 01 10:34:48 volumio volumio[29657]: info: Loading plugins from folder /volumio/app/plugins/ May 01 10:34:48 volumio volumio[29657]: info: Adding plugin upnp to MyMusic Plugins May 01 10:34:48 volumio volumio[29657]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 10:34:48 volumio volumio[29657]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 10:34:48 volumio volumio[29657]: info: Loading plugins from folder /data/plugins/ May 01 10:34:48 volumio volumio[29657]: info: Loading plugin "system"... May 01 10:34:48 volumio volumio[29657]: info: Loading plugin "appearance"... May 01 10:34:48 volumio volumio[29657]: info: Loading plugin "network"... May 01 10:34:48 volumio volumio[29657]: info: Refreshing Cached IP Addresses May 01 10:34:48 volumio sudo[29684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 10:34:48 volumio sudo[29684]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:48 volumio sudo[29686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 10:34:48 volumio sudo[29684]: pam_unix(sudo:session): session closed for user root May 01 10:34:48 volumio sudo[29686]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:48 volumio sudo[29686]: pam_unix(sudo:session): session closed for user root May 01 10:34:48 volumio volumio[29657]: info: Loading plugin "services"... May 01 10:34:48 volumio volumio[29657]: info: Loading plugin "alsa_controller"... May 01 10:34:48 volumio sudo[29695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 10:34:48 volumio sudo[29695]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:48 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 10:34:48 volumio volumio[29657]: info: Loading plugin "wizard"... May 01 10:34:48 volumio volumio[29657]: info: Loading plugin "networkfs"... May 01 10:34:49 volumio volumio[29657]: info: Starting Udev Watcher for removable devices May 01 10:34:49 volumio sudo[29712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.31.52:/AUDIO-MUSIC /mnt/NAS/iconBIT May 01 10:34:49 volumio sudo[29712]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 10:34:49 volumio volumio[29657]: info: Ignoring mount for partition: boot May 01 10:34:49 volumio volumio[29657]: info: Ignoring mount for partition: volumio May 01 10:34:49 volumio volumio[29657]: info: Ignoring mount for partition: volumio_data May 01 10:34:49 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "volumio_command_line_client"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "upnp"... May 01 10:34:49 volumio volumio[29657]: info: [1746084889064] Starting Upmpd Daemon May 01 10:34:49 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "my_music"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "mpd"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "upnp_browser"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "alarm-clock"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "airplay_emulation"... May 01 10:34:49 volumio volumio[29657]: info: Starting Shairport Sync May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "last_100"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "webradio"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "i2s_dacs"... May 01 10:34:49 volumio volumio[29657]: info: I2S DAC not set, start Auto-detection May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "volumiodiscovery"... May 01 10:34:49 volumio volumio[29657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:34:49 volumio volumio[29657]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:49 volumio volumio[29657]: *** WARNING *** For more information see May 01 10:34:49 volumio node[29657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 10:34:49 volumio volumio[29657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:34:49 volumio volumio[29657]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:49 volumio volumio[29657]: *** WARNING *** For more information see May 01 10:34:49 volumio node[29657]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:49 volumio node[29657]: *** WARNING *** For more information see May 01 10:34:49 volumio node[29657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 10:34:49 volumio node[29657]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 10:34:49 volumio node[29657]: *** WARNING *** For more information see May 01 10:34:49 volumio volumio[29657]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 10:34:49 volumio volumio[29657]: info: Discovery: Started advertising with name: Volumio May 01 10:34:49 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "outputs"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "albumart"... May 01 10:34:49 volumio volumio[29657]: info: Plugin example_plugin is not enabled May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "inputs"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "updater_comm"... May 01 10:34:49 volumio volumio[29657]: info: Plugin mpdemulation is not enabled May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "rest_api"... May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "websocket"... May 01 10:34:49 volumio volumio[29657]: info: Starting Socket.io Server version 2.3.0 May 01 10:34:49 volumio volumio[29657]: info: Loading plugin "touch_display"... May 01 10:34:50 volumio volumio[29657]: Forking 3 albumart workers May 01 10:34:50 volumio volumio[29657]: info: Applying required configuration parameters for plugin touch_display May 01 10:34:50 volumio sudo[29695]: pam_unix(sudo:session): session closed for user root May 01 10:34:50 volumio volumio[29657]: info: Loading i18n strings for locale ru May 01 10:34:50 volumio volumio[29657]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 01 10:34:50 volumio volumio[29657]: Updating browse sources language May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::initPlayerControls May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 10:34:50 volumio volumio[29657]: Express server listening on port 3000 May 01 10:34:50 volumio volumio[29657]: [Metrics] WebUI: 3s 626.58ms May 01 10:34:50 volumio volumio[29657]: info: CoreStateMachine::resetVolumioState May 01 10:34:50 volumio volumio[29657]: info: CoreStateMachine::getcurrentVolume May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:34:50 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 10:34:50 volumio volumio[29657]: info: Volumio Network Manager: Network status updated: 3 May 01 10:34:51 volumio volumio[29657]: Starting albumart workers May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 May 01 10:34:51 volumio volumio-remote-updater[701]: [2025-05-01 10:34:51] [connect] Successful connection May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.57 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 May 01 10:34:51 volumio volumio[29657]: Unable to parse: May 01 10:34:51 volumio volumio[29657]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:51 volumio volumio[29657]: Capabilities: pswitch May 01 10:34:51 volumio volumio[29657]: Playback channels: Front Left - Front Right May 01 10:34:51 volumio volumio[29657]: Mono: May 01 10:34:51 volumio volumio[29657]: Front Left: Playback [on] May 01 10:34:51 volumio volumio[29657]: Front Right: Playback [on] May 01 10:34:51 volumio volumio[29657]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:34:51 volumio volumio[29657]: Starting albumart workers May 01 10:34:51 volumio volumio[29657]: Starting albumart workers May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 192.168.31.58 from 192.168.31.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 01 10:34:51 volumio volumio[29657]: info: Reloading queue from file May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio-remote-updater[701]: [2025-05-01 10:34:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746084891 101 May 01 10:34:51 volumio volumio[29657]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 11 May 01 10:34:51 volumio volumio[29657]: Unable to parse: May 01 10:34:51 volumio volumio[29657]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:51 volumio volumio[29657]: Capabilities: pswitch May 01 10:34:51 volumio volumio[29657]: Playback channels: Front Left - Front Right May 01 10:34:51 volumio volumio[29657]: Mono: May 01 10:34:51 volumio volumio[29657]: Front Left: Playback [on] May 01 10:34:51 volumio volumio[29657]: Front Right: Playback [on] May 01 10:34:51 volumio volumio[29657]: info: VolumeController:: Volume=undefined Mute =false May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::pushState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioPushState May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::updateTrackBlock May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrackBlock May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioRetrievevolume May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::setRepeat false single undefined May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::pushState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioPushState May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::setRandom null May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::pushState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioPushState May 01 10:34:51 volumio volumio[29657]: info: Setting Device type: Raspberry PI May 01 10:34:51 volumio volumio[29657]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 01 10:34:51 volumio volumio[29657]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 May 01 10:34:51 volumio volumio[29657]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 01 10:34:51 volumio volumio[29657]: info: Received Get System Info May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:51 volumio volumio[29657]: info: Discovery: Getting this device information May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:34:51 volumio volumio[29657]: info: Received Get System Info May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:51 volumio volumio[29657]: info: Discovery: Getting this device information May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: Listing playlists May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetQueue May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::getQueue May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getQueue May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 10:34:51 volumio volumio[29657]: Unable to parse: May 01 10:34:51 volumio volumio[29657]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:51 volumio volumio[29657]: Capabilities: pswitch May 01 10:34:51 volumio volumio[29657]: Playback channels: Front Left - Front Right May 01 10:34:51 volumio volumio[29657]: Mono: May 01 10:34:51 volumio volumio[29657]: Front Left: Playback [on] May 01 10:34:51 volumio volumio[29657]: Front Right: Playback [on] May 01 10:34:51 volumio volumio[29657]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 10:34:51 volumio volumio[29657]: info: Discovery: adding 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:34:51 volumio volumio[29657]: info: Discovery: Found device Volumio May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: Unable to parse: May 01 10:34:51 volumio volumio[29657]: Simple mixer control 'Cambridge Audio USB 1.0 Audio In Playback S',0 May 01 10:34:51 volumio volumio[29657]: Capabilities: pswitch May 01 10:34:51 volumio volumio[29657]: Playback channels: Front Left - Front Right May 01 10:34:51 volumio volumio[29657]: Mono: May 01 10:34:51 volumio volumio[29657]: Front Left: Playback [on] May 01 10:34:51 volumio volumio[29657]: Front Right: Playback [on] May 01 10:34:51 volumio volumio[29657]: info: VolumeController:: Volume=undefined Mute =false May 01 10:34:51 volumio volumio[29657]: info: CoreStateMachine::pushState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioPushState May 01 10:34:51 volumio volumio[29657]: info: Discovery: this is already registered, 118f10b9-f11c-4a2f-b679-dcfe146d1a0e May 01 10:34:51 volumio volumio[29657]: info: Discovery: Found device Volumio May 01 10:34:51 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:51 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:54 volumio volumio[29657]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::volumioGetVisibleSources May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:54 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 10:34:54 volumio volumio[29657]: info: Received Get System Info May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 10:34:54 volumio volumio[29657]: info: Discovery: Getting this device information May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:54 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::volumioGetState May 01 10:34:54 volumio volumio[29657]: info: CorePlayQueue::getTrack 0 May 01 10:34:54 volumio volumio[29657]: info: Listing playlists May 01 10:34:54 volumio volumio[29657]: info: CoreCommandRouter::volumioGetQueue May 01 10:34:54 volumio volumio[29657]: info: CoreStateMachine::getQueue May 01 10:34:54 volumio volumio[29657]: info: CorePlayQueue::getQueue May 01 10:34:56 volumio volumio[29657]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 01 10:34:56 volumio volumio[29657]: verbose: ControllerMpd::sendMpdCommand update May 01 10:34:56 volumio volumio[29657]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:56 volumio volumio[29657]: TypeError: Cannot read property 'then' of undefined May 01 10:34:56 volumio volumio[29657]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) May 01 10:34:56 volumio volumio[29657]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) May 01 10:34:56 volumio volumio[29657]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 01 10:34:56 volumio volumio[29657]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1091:26) May 01 10:34:56 volumio volumio[29657]: at Socket.emit (events.js:315:20) May 01 10:34:56 volumio volumio[29657]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 01 10:34:56 volumio volumio[29657]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 01 10:34:56 volumio volumio[29657]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 10:34:57 volumio sudo[29801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 10:33 May 01 10:34:57 volumio sudo[29801]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"