-- Logs begin at Thu 2019-02-14 12:11:58 IST, end at Wed 2025-06-18 18:53:03 IDT. -- Jun 18 18:52:01 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 18 18:52:01 volumio volumio[1256]: info: CURURI: music-library Jun 18 18:52:01 volumio volumio[1256]: info: Preload queue cleared Jun 18 18:52:01 volumio volumio[1256]: info: MYVOLUMIO: Adding device Jun 18 18:52:01 volumio volumio[1256]: info: MYVOLUMIO: Evaluating Server Jun 18 18:52:02 volumio volumio[1505]: ....................................................................................................................++++ Jun 18 18:52:02 volumio volumio[1505]: e is 65537 (0x010001) Jun 18 18:52:02 volumio volumio[1505]: writing RSA key Jun 18 18:52:03 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 18 18:52:03 volumio volumio[1256]: info: CURURI: music-library/NAS Jun 18 18:52:03 volumio volumio[1256]: info: Preload queue cleared Jun 18 18:52:03 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 18 18:52:03 volumio volumio[1256]: info: CURURI: music-library/NAS/Music Jun 18 18:52:04 volumio volumio[1256]: info: Preload queue cleared Jun 18 18:52:04 volumio volumio[1256]: info: Preloading song: music-library/NAS/Music/VID-20181210-WA0030.mp4 Jun 18 18:52:04 volumio volumio[1256]: info: Exploding uri music-library/NAS/Music/VID-20181210-WA0030.mp4 in service mpd Jun 18 18:52:04 volumio volumio[1256]: info: ALBUMART /albumart?cacheid=114&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FVID-20181210-WA0030.mp4&metadata=false Jun 18 18:52:04 volumio volumio[1256]: info: URI /mnt/NAS/Music/VID-20181210-WA0030.mp4 Jun 18 18:52:04 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 18 18:52:04 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 18 18:52:04 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:04 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:07 volumio volumio[1256]: info: Setting Geolocation for MyVolumio to eu4 Jun 18 18:52:07 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:07 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:07 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:08 volumio volumio[1256]: info: Updating MyVolumio device info Jun 18 18:52:08 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:08 volumio volumio[1256]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 18 18:52:09 volumio volumio-remote-updater[588]: No test mode Jun 18 18:52:09 volumio volumio-remote-updater[588]: No alpha test mode Jun 18 18:52:09 volumio volumio[1256]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::volumioGetBrowseSources Jun 18 18:52:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:10 volumio volumio[1256]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 18 18:52:10 volumio volumio[1256]: info: Completed starting MyVolumio Plugin Jun 18 18:52:10 volumio volumio[1256]: [Metrics] CommandRouter: 35s 785.99ms Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::volumiosetStartupVolume Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jun 18 18:52:10 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent Jun 18 18:52:10 volumio kernel: SQUASHFS error: zlib decompression failed, data probably corrupt Jun 18 18:52:10 volumio kernel: SQUASHFS error: Failed to read block 0x1c315fe8: -5 Jun 18 18:52:11 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Jun 18 18:52:11 volumio systemd[1]: volumio.service: Failed with result 'signal'. Jun 18 18:52:11 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:11 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:11 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 18:52:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 18 18:52:11 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:11 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 18:52:11 volumio systemd[1]: Started Volumio Backend Module. Jun 18 18:52:11 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:12 volumio volumio[1680]: info: ------------------------------------------- Jun 18 18:52:12 volumio volumio[1680]: info: ----- Volumio3 ---- Jun 18 18:52:12 volumio volumio[1680]: info: ------------------------------------------- Jun 18 18:52:12 volumio volumio[1680]: info: ----- System startup ---- Jun 18 18:52:12 volumio volumio[1680]: info: ------------------------------------------- Jun 18 18:52:12 volumio volumio[1680]: info: MYVOLUMIO Environment detected Jun 18 18:52:12 volumio volumio[1680]: info: Plugin folders cleanup Jun 18 18:52:12 volumio volumio[1680]: info: Scanning into folder /volumio/app/plugins/ Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category audio_interface Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category miscellanea Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category music_service Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category plugins.json Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category system_controller Jun 18 18:52:12 volumio volumio[1680]: info: Scanning category user_interface Jun 18 18:52:12 volumio volumio[1680]: info: Scanning into folder /data/plugins/ Jun 18 18:52:12 volumio volumio[1680]: info: Plugin folders cleanup completed Jun 18 18:52:12 volumio volumio[1680]: info: ------------------------------------------- Jun 18 18:52:12 volumio volumio[1680]: info: ----- Core plugins startup ---- Jun 18 18:52:12 volumio volumio[1680]: info: ------------------------------------------- Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 18:52:12 volumio volumio[1680]: info: Adding plugin upnp to MyMusic Plugins Jun 18 18:52:12 volumio volumio[1680]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 18:52:12 volumio volumio[1680]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugins from folder /data/plugins/ Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugin "system"... Jun 18 18:52:12 volumio volumio[1680]: info: Loading plugin "appearance"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "network"... Jun 18 18:52:13 volumio volumio[1680]: info: Refreshing Cached IP Addresses Jun 18 18:52:13 volumio sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 18:52:13 volumio sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:13 volumio sudo[1706]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:13 volumio sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 18:52:13 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:13 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "services"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "alsa_controller"... Jun 18 18:52:13 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 18:52:13 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "wizard"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "networkfs"... Jun 18 18:52:13 volumio volumio[1680]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jun 18 18:52:13 volumio volumio[1680]: info: Starting Udev Watcher for removable devices Jun 18 18:52:13 volumio volumio[1680]: info: Ignoring mount for partition: boot Jun 18 18:52:13 volumio volumio[1680]: info: Ignoring mount for partition: volumio Jun 18 18:52:13 volumio volumio[1680]: info: Ignoring mount for partition: volumio_data Jun 18 18:52:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "volumio_command_line_client"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "upnp"... Jun 18 18:52:13 volumio volumio[1680]: info: [1750261933500] Starting Upmpd Daemon Jun 18 18:52:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "my_music"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "mpd"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "upnp_browser"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "alarm-clock"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "airplay_emulation"... Jun 18 18:52:13 volumio volumio[1680]: info: Starting Shairport Sync Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "last_100"... Jun 18 18:52:13 volumio volumio[1680]: info: Loading plugin "webradio"... Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "i2s_dacs"... Jun 18 18:52:14 volumio volumio[1680]: info: I2S DAC not set, start Auto-detection Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "volumiodiscovery"... Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:14 volumio node[1680]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** For more information see Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:14 volumio volumio[1680]: *** WARNING *** For more information see Jun 18 18:52:14 volumio node[1680]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:14 volumio node[1680]: *** WARNING *** For more information see Jun 18 18:52:14 volumio node[1680]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:14 volumio node[1680]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:14 volumio node[1680]: *** WARNING *** For more information see Jun 18 18:52:14 volumio volumio[1680]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 18:52:14 volumio volumio[1680]: info: Discovery: Started advertising with name: Volumio Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "outputs"... Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "albumart"... Jun 18 18:52:14 volumio volumio[1680]: info: Plugin example_plugin is not enabled Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "inputs"... Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "updater_comm"... Jun 18 18:52:14 volumio volumio[1680]: info: Plugin mpdemulation is not enabled Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "rest_api"... Jun 18 18:52:14 volumio volumio[1680]: info: Loading plugin "websocket"... Jun 18 18:52:14 volumio volumio[1680]: info: Starting Socket.io Server version 2.3.0 Jun 18 18:52:14 volumio volumio[1680]: info: Loading i18n strings for locale ru Jun 18 18:52:14 volumio volumio[1680]: Updating browse sources language Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:14 volumio volumio[1680]: Forking 3 albumart workers Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::initPlayerControls Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:14 volumio volumio[1680]: Express server listening on port 3000 Jun 18 18:52:14 volumio volumio[1680]: [Metrics] WebUI: 2s 453.72ms Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::resetVolumioState Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::getcurrentVolume Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:14 volumio volumio[1680]: info: Volumio Network Manager: Network status updated: 3 Jun 18 18:52:14 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 18 18:52:14 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 18:52:14 volumio volumio-remote-updater[588]: [2025-06-18 18:52:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 18:52:14 volumio volumio-remote-updater[588]: [2025-06-18 18:52:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 18:52:14 volumio volumio[1680]: Starting albumart workers Jun 18 18:52:14 volumio volumio[1680]: Starting albumart workers Jun 18 18:52:14 volumio volumio[1680]: Starting albumart workers Jun 18 18:52:14 volumio volumio[1680]: info: Reloading queue from file Jun 18 18:52:14 volumio volumio[1680]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::pushState Jun 18 18:52:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:14 volumio volumio[1680]: info: CoreStateMachine::updateTrackBlock Jun 18 18:52:14 volumio volumio[1680]: info: CorePlayQueue::getTrackBlock Jun 18 18:52:14 volumio volumio[1680]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::setRepeat false single undefined Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::pushState Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::setRandom null Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::pushState Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:15 volumio volumio[1680]: info: Setting Device type: Raspberry PI Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 18:52:15 volumio volumio[1680]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 18 18:52:15 volumio volumio[1680]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 18 18:52:15 volumio volumio[1680]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 18 18:52:15 volumio volumio[1680]: info: Completed loading Core Plugins Jun 18 18:52:15 volumio volumio[1680]: info: Preparing to generate the ALSA configuration file Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: adding 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: Found device Volumio Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: this is already registered, 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:15 volumio volumio[1680]: info: Discovery: Found device Volumio Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:15 volumio volumio[1680]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::pushState Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:15 volumio volumio[1680]: info: Asound.conf file unchanged, so no further update is needed Jun 18 18:52:15 volumio volumio[1680]: info: Output device has changed, restarting MPD Jun 18 18:52:15 volumio volumio[1680]: info: Output device has changed, restarting Shairport Sync Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:15 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:15 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:15 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:15 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:15 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:15 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 18:52:15 volumio volumio[1680]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:15 volumio volumio[1680]: info: ___________ START PLUGINS ___________ Jun 18 18:52:15 volumio volumio[1680]: info: ControllerMpd::onStart: Initializing MPD Jun 18 18:52:15 volumio volumio[1680]: info: Creating MPD Configuration file Jun 18 18:52:15 volumio systemd[1]: mpd.service: Succeeded. Jun 18 18:52:15 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:15 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:15 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:15 volumio volumio[1680]: info: [1750261935425] CoreMusicLibrary::Adding element Медиа-серверы Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:15 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:15 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:15 volumio sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:15 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:15 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 18:52:15 volumio systemd[1]: mpd.service: Succeeded. Jun 18 18:52:15 volumio volumio[1680]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:15 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:15 volumio volumio[1680]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:15 volumio volumio[1680]: info: [1750261935525] CoreMusicLibrary::Adding element Last_100 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:15 volumio volumio[1680]: info: [1750261935527] CoreMusicLibrary::Adding element Webradio Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:15 volumio volumio[1680]: info: Initializing BBC Radios Jun 18 18:52:15 volumio sudo[1819]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 18:52:15 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:15 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: Volumio Calling Home Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::volumioGetQueue Jun 18 18:52:15 volumio volumio[1680]: info: CoreStateMachine::getQueue Jun 18 18:52:15 volumio volumio[1680]: info: CorePlayQueue::getQueue Jun 18 18:52:15 volumio volumio[1680]: info: Listing playlists Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 18:52:15 volumio volumio[1680]: info: MPD Permissions set Jun 18 18:52:15 volumio volumio[1680]: info: MPD Permissions set Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 18 18:52:15 volumio volumio[1680]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 18 18:52:16 volumio volumio[1680]: info: Starting Shairport Sync Jun 18 18:52:16 volumio volumio[1680]: info: Starting Shairport Sync Jun 18 18:52:16 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:16 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:16 volumio volumio[1680]: info: Starting Shairport Sync Jun 18 18:52:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:16 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:16 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:16 volumio sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:16 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:16 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:16 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:16 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:16 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:16 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:16 volumio volumio[1680]: info: Shairport-Sync Started Jun 18 18:52:16 volumio volumio[1680]: Error adding Membership: Error: addMembership EINVAL Jun 18 18:52:16 volumio volumio[1680]: info: Shairport-Sync Started Jun 18 18:52:16 volumio volumio[1680]: info: Shairport-Sync Started Jun 18 18:52:16 volumio mpd[1831]: Jun 18 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 18:52:16 volumio systemd[1]: Started Music Player Daemon. Jun 18 18:52:16 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:16 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:16 volumio volumio[1680]: info: Completed starting Core Plugins Jun 18 18:52:16 volumio volumio[1680]: info: ------------------------------------------- Jun 18 18:52:16 volumio volumio[1680]: info: ----- MyVolumio plugins startup ---- Jun 18 18:52:16 volumio volumio[1680]: info: ------------------------------------------- Jun 18 18:52:16 volumio volumio[1680]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 18:52:16 volumio volumio[1680]: error: MPD error: The expression evaluated to a falsy value: Jun 18 18:52:16 volumio volumio[1680]: assert.ok(self.idling) Jun 18 18:52:16 volumio volumio[1680]: error: The expression evaluated to a falsy value: Jun 18 18:52:16 volumio volumio[1680]: assert.ok(self.idling) Jun 18 18:52:16 volumio volumio[1680]: info: MPD running with PID1831 Jun 18 18:52:16 volumio volumio[1680]: ,establishing connection Jun 18 18:52:16 volumio volumio[1680]: error: updateQueue error: null Jun 18 18:52:16 volumio volumio[1680]: error: updateQueue error: null Jun 18 18:52:17 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:18 volumio volumio[1680]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Jun 18 18:52:19 volumio volumio-remote-updater[588]: [2025-06-18 18:52:19] [connect] Successful connection Jun 18 18:52:19 volumio volumio-remote-updater[588]: [2025-06-18 18:52:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750261939 101 Jun 18 18:52:19 volumio volumio[1680]: 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: 8 Jun 18 18:52:20 volumio volumio[1680]: info: Volumio called home Jun 18 18:52:21 volumio volumio[1680]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 18 18:52:23 volumio volumio[1680]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds Jun 18 18:52:23 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 18:52:23 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:23 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:23 volumio volumio[1680]: Worker 1 died :( Jun 18 18:52:23 volumio sudo[1885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 18 18:52:23 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:23 volumio volumio-remote-updater[588]: [2025-06-18 18:52:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 18:52:23 volumio volumio-remote-updater[588]: [2025-06-18 18:52:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 18:52:23 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Jun 18 18:52:23 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:23 volumio systemd[1]: volumio.service: Failed with result 'signal'. Jun 18 18:52:23 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:23 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 18:52:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jun 18 18:52:23 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 18:52:23 volumio systemd[1]: Started Volumio Backend Module. Jun 18 18:52:23 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:23 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:24 volumio volumio[1898]: info: ------------------------------------------- Jun 18 18:52:24 volumio volumio[1898]: info: ----- Volumio3 ---- Jun 18 18:52:24 volumio volumio[1898]: info: ------------------------------------------- Jun 18 18:52:24 volumio volumio[1898]: info: ----- System startup ---- Jun 18 18:52:24 volumio volumio[1898]: info: ------------------------------------------- Jun 18 18:52:25 volumio volumio[1898]: info: MYVOLUMIO Environment detected Jun 18 18:52:25 volumio volumio[1898]: info: Plugin folders cleanup Jun 18 18:52:25 volumio volumio[1898]: info: Scanning into folder /volumio/app/plugins/ Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category audio_interface Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category miscellanea Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category music_service Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category plugins.json Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category system_controller Jun 18 18:52:25 volumio volumio[1898]: info: Scanning category user_interface Jun 18 18:52:25 volumio volumio[1898]: info: Scanning into folder /data/plugins/ Jun 18 18:52:25 volumio volumio[1898]: info: Plugin folders cleanup completed Jun 18 18:52:25 volumio volumio[1898]: info: ------------------------------------------- Jun 18 18:52:25 volumio volumio[1898]: info: ----- Core plugins startup ---- Jun 18 18:52:25 volumio volumio[1898]: info: ------------------------------------------- Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 18:52:25 volumio volumio[1898]: info: Adding plugin upnp to MyMusic Plugins Jun 18 18:52:25 volumio volumio[1898]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 18:52:25 volumio volumio[1898]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugins from folder /data/plugins/ Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugin "system"... Jun 18 18:52:25 volumio volumio[1898]: info: Loading plugin "appearance"... Jun 18 18:52:25 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Jun 18 18:52:25 volumio systemd[1]: volumio.service: Failed with result 'signal'. Jun 18 18:52:25 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 18:52:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jun 18 18:52:25 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 18:52:25 volumio systemd[1]: Started Volumio Backend Module. Jun 18 18:52:25 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:26 volumio volumio[1925]: info: ------------------------------------------- Jun 18 18:52:26 volumio volumio[1925]: info: ----- Volumio3 ---- Jun 18 18:52:26 volumio volumio[1925]: info: ------------------------------------------- Jun 18 18:52:26 volumio volumio[1925]: info: ----- System startup ---- Jun 18 18:52:26 volumio volumio[1925]: info: ------------------------------------------- Jun 18 18:52:26 volumio volumio[1925]: info: MYVOLUMIO Environment detected Jun 18 18:52:26 volumio volumio[1925]: info: Plugin folders cleanup Jun 18 18:52:26 volumio volumio[1925]: info: Scanning into folder /volumio/app/plugins/ Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category audio_interface Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category miscellanea Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category music_service Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category plugins.json Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category system_controller Jun 18 18:52:26 volumio volumio[1925]: info: Scanning category user_interface Jun 18 18:52:26 volumio volumio[1925]: info: Scanning into folder /data/plugins/ Jun 18 18:52:26 volumio volumio[1925]: info: Plugin folders cleanup completed Jun 18 18:52:26 volumio volumio[1925]: info: ------------------------------------------- Jun 18 18:52:26 volumio volumio[1925]: info: ----- Core plugins startup ---- Jun 18 18:52:26 volumio volumio[1925]: info: ------------------------------------------- Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 18:52:26 volumio volumio[1925]: info: Adding plugin upnp to MyMusic Plugins Jun 18 18:52:26 volumio volumio[1925]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 18:52:26 volumio volumio[1925]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugins from folder /data/plugins/ Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugin "system"... Jun 18 18:52:26 volumio volumio[1925]: info: Loading plugin "appearance"... Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "network"... Jun 18 18:52:27 volumio volumio[1925]: info: Refreshing Cached IP Addresses Jun 18 18:52:27 volumio sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 18:52:27 volumio sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:27 volumio sudo[1951]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:27 volumio sudo[1953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 18:52:27 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:27 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "services"... Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "alsa_controller"... Jun 18 18:52:27 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 18:52:27 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:27 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "wizard"... Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "networkfs"... Jun 18 18:52:27 volumio volumio[1925]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jun 18 18:52:27 volumio volumio[1925]: info: Starting Udev Watcher for removable devices Jun 18 18:52:27 volumio volumio[1925]: info: Ignoring mount for partition: boot Jun 18 18:52:27 volumio volumio[1925]: info: Ignoring mount for partition: volumio Jun 18 18:52:27 volumio volumio[1925]: info: Ignoring mount for partition: volumio_data Jun 18 18:52:27 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "volumio_command_line_client"... Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "upnp"... Jun 18 18:52:27 volumio volumio[1925]: info: [1750261947694] Starting Upmpd Daemon Jun 18 18:52:27 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "my_music"... Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "mpd"... Jun 18 18:52:27 volumio volumio[1925]: info: Loading plugin "upnp_browser"... Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "alarm-clock"... Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "airplay_emulation"... Jun 18 18:52:28 volumio volumio[1925]: info: Starting Shairport Sync Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "last_100"... Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "webradio"... Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "i2s_dacs"... Jun 18 18:52:28 volumio volumio[1925]: info: I2S DAC not set, start Auto-detection Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "volumiodiscovery"... Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:28 volumio node[1925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** For more information see Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:28 volumio volumio[1925]: *** WARNING *** For more information see Jun 18 18:52:28 volumio node[1925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:28 volumio node[1925]: *** WARNING *** For more information see Jun 18 18:52:28 volumio node[1925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:28 volumio node[1925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:28 volumio node[1925]: *** WARNING *** For more information see Jun 18 18:52:28 volumio volumio[1925]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 18:52:28 volumio volumio[1925]: info: Discovery: Started advertising with name: Volumio Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "outputs"... Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "albumart"... Jun 18 18:52:28 volumio volumio[1925]: info: Plugin example_plugin is not enabled Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "inputs"... Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "updater_comm"... Jun 18 18:52:28 volumio volumio[1925]: info: Plugin mpdemulation is not enabled Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "rest_api"... Jun 18 18:52:28 volumio volumio[1925]: info: Loading plugin "websocket"... Jun 18 18:52:28 volumio volumio[1925]: info: Starting Socket.io Server version 2.3.0 Jun 18 18:52:28 volumio volumio[1925]: info: Loading i18n strings for locale ru Jun 18 18:52:28 volumio volumio[1925]: Updating browse sources language Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:28 volumio volumio[1925]: Forking 3 albumart workers Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::initPlayerControls Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: Express server listening on port 3000 Jun 18 18:52:28 volumio volumio[1925]: [Metrics] WebUI: 2s 426.60ms Jun 18 18:52:28 volumio volumio-remote-updater[588]: [2025-06-18 18:52:28] [connect] Successful connection Jun 18 18:52:28 volumio volumio[1925]: info: CoreStateMachine::resetVolumioState Jun 18 18:52:28 volumio volumio[1925]: info: CoreStateMachine::getcurrentVolume Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:28 volumio volumio[1925]: info: Volumio Network Manager: Network status updated: 3 Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 18 18:52:28 volumio volumio[1925]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 18 18:52:28 volumio volumio-remote-updater[588]: [2025-06-18 18:52:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750261948 101 Jun 18 18:52:28 volumio volumio[1925]: 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: 7 Jun 18 18:52:29 volumio volumio[1925]: info: Reloading queue from file Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::setRepeat false single undefined Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::setRandom null Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:29 volumio volumio[1925]: info: Setting Device type: Raspberry PI Jun 18 18:52:29 volumio volumio[1925]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::updateTrackBlock Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrackBlock Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:29 volumio volumio[1925]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 18 18:52:29 volumio volumio[1925]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 18 18:52:29 volumio volumio[1925]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 18 18:52:29 volumio volumio[1925]: Starting albumart workers Jun 18 18:52:29 volumio volumio[1925]: Starting albumart workers Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:29 volumio volumio[1925]: Starting albumart workers Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetQueue Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::getQueue Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getQueue Jun 18 18:52:29 volumio volumio[1925]: info: Listing playlists Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 18:52:29 volumio volumio[1925]: info: Completed loading Core Plugins Jun 18 18:52:29 volumio volumio[1925]: info: Preparing to generate the ALSA configuration file Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: adding 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: Found device Volumio Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:29 volumio volumio[1925]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:29 volumio volumio[1925]: info: CoreStateMachine::pushState Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:29 volumio volumio[1925]: info: Asound.conf file unchanged, so no further update is needed Jun 18 18:52:29 volumio volumio[1925]: info: Output device has changed, restarting MPD Jun 18 18:52:29 volumio sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:29 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:29 volumio volumio[1925]: info: Output device has changed, restarting Shairport Sync Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: Worker 2 died :( Jun 18 18:52:29 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:29 volumio sudo[2024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:29 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:29 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 18:52:29 volumio systemd[1]: mpd.service: Succeeded. Jun 18 18:52:29 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:29 volumio volumio[1925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:29 volumio volumio[1925]: info: ___________ START PLUGINS ___________ Jun 18 18:52:29 volumio volumio[1925]: info: ControllerMpd::onStart: Initializing MPD Jun 18 18:52:29 volumio volumio[1925]: info: Creating MPD Configuration file Jun 18 18:52:29 volumio volumio[1925]: Worker 3 died :( Jun 18 18:52:29 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:29 volumio volumio[1925]: info: [1750261949501] CoreMusicLibrary::Adding element Медиа-серверы Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:29 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:29 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:29 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:29 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:29 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:29 volumio sudo[2037]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 18:52:29 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:29 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:29 volumio volumio[1925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 18 18:52:29 volumio systemd[1]: mpd.service: Succeeded. Jun 18 18:52:29 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:29 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:29 volumio volumio[1925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:29 volumio volumio[1925]: info: [1750261949677] CoreMusicLibrary::Adding element Last_100 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:29 volumio volumio[1925]: info: [1750261949680] CoreMusicLibrary::Adding element Webradio Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:29 volumio volumio[1925]: info: Initializing BBC Radios Jun 18 18:52:29 volumio sudo[2067]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 18:52:29 volumio sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:29 volumio sudo[2067]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: info: Volumio Calling Home Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: this is already registered, 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:29 volumio volumio[1925]: info: Discovery: Found device Volumio Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:29 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:29 volumio volumio[1925]: info: MPD Permissions set Jun 18 18:52:29 volumio volumio[1925]: info: MPD Permissions set Jun 18 18:52:29 volumio volumio[1925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:29 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:30 volumio volumio[1925]: Starting albumart workers Jun 18 18:52:30 volumio volumio[1925]: Starting albumart workers Jun 18 18:52:30 volumio volumio[1925]: info: Volumio called home Jun 18 18:52:30 volumio volumio[1925]: info: Starting Shairport Sync Jun 18 18:52:30 volumio volumio[1925]: info: Starting Shairport Sync Jun 18 18:52:30 volumio sudo[2092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:30 volumio volumio[1925]: info: Starting Shairport Sync Jun 18 18:52:30 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:30 volumio sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:30 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:30 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:30 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:30 volumio volumio[1925]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:30 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:30 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:30 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:30 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 18:52:30 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:30 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:30 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:30 volumio volumio[1925]: info: Shairport-Sync Started Jun 18 18:52:30 volumio volumio[1925]: Error adding Membership: Error: addMembership EINVAL Jun 18 18:52:30 volumio volumio[1925]: info: Shairport-Sync Started Jun 18 18:52:30 volumio volumio[1925]: info: Shairport-Sync Started Jun 18 18:52:30 volumio mpd[2076]: Jun 18 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 18:52:30 volumio systemd[1]: Started Music Player Daemon. Jun 18 18:52:30 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:30 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:30 volumio volumio[1925]: info: Completed starting Core Plugins Jun 18 18:52:30 volumio volumio[1925]: info: ------------------------------------------- Jun 18 18:52:30 volumio volumio[1925]: info: ----- MyVolumio plugins startup ---- Jun 18 18:52:30 volumio volumio[1925]: info: ------------------------------------------- Jun 18 18:52:30 volumio volumio[1925]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 18:52:30 volumio volumio[1925]: error: MPD error: The expression evaluated to a falsy value: Jun 18 18:52:30 volumio volumio[1925]: assert.ok(self.idling) Jun 18 18:52:30 volumio volumio[1925]: error: The expression evaluated to a falsy value: Jun 18 18:52:30 volumio volumio[1925]: assert.ok(self.idling) Jun 18 18:52:30 volumio volumio[1925]: info: MPD running with PID2076 Jun 18 18:52:30 volumio volumio[1925]: ,establishing connection Jun 18 18:52:30 volumio volumio[1925]: error: updateQueue error: null Jun 18 18:52:30 volumio volumio[1925]: error: updateQueue error: null Jun 18 18:52:31 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:32 volumio volumio[1925]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Jun 18 18:52:35 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 18 18:52:35 volumio volumio[1925]: info: CURURI: music-library/NAS/Music/Phil Collins Jun 18 18:52:35 volumio volumio[1925]: info: Preload queue cleared Jun 18 18:52:36 volumio volumio[1925]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 18 18:52:37 volumio volumio[1925]: Stacktrace: Jun 18 18:52:37 volumio volumio[1925]: ptr1=0x29b8e80d Jun 18 18:52:37 volumio volumio[1925]: ptr2=(nil) Jun 18 18:52:37 volumio volumio[1925]: ptr3=(nil) Jun 18 18:52:37 volumio volumio[1925]: ptr4=(nil) Jun 18 18:52:37 volumio volumio[1925]: failure_message_object=0xbe87d3e4 Jun 18 18:52:37 volumio volumio[1925]: ==== JS stack trace ========================================= Jun 18 18:52:37 volumio volumio[1925]: 0: ExitFrame [pc: 0xf38000] Jun 18 18:52:37 volumio volumio[1925]: 1: StubFrame [pc: 0xfaf400] Jun 18 18:52:37 volumio volumio[1925]: Security context: 0x29b8e80d Jun 18 18:52:37 volumio volumio[1925]: 2: /* anonymous */(aka /* anonymous */) [0x40363ebd] [/volumio/app/plugins/system_controller/networkfs/index.js:254] [bytecode=0x53d3d121 offset=205](this=0x344c027d ,0x40366405 ) Jun 18 18:52:37 volumio volumio[1925]: 3: mount [0x503b13a9] [/volumio/node_modules/linux-mountutils/mountutils.js:97] [bytecode=0x53d3ba75 offset=55](this=0x39903c3d ,0x40363969 ,0x40363e5d ,0x40363ea5 ,0x40363ebd ) Jun 18 18:52:37 volumio volumio[1925]: 4: mountShare [0x53d3a071] [/volumio/app/plugins/system_controller/networkfs/index.js:239] [bytecode=0x53d3b05d offset=757](this=0x280a13bd ,0x403634f1 ) Jun 18 18:52:37 volumio volumio[1925]: 5: _onTimeout [0x5960b595] [/volumio/app/plugins/system_controller/networkfs/index.js:255] [bytecode=0x3e49914d offset=34](this=0x4f74a2b9 ) Jun 18 18:52:37 volumio volumio[1925]: 6: listOnTimeout(aka listOnTimeout) [0x5d5e6495] [internal/timers.js:554] [bytecode=0x58bde525 offset=377](this=0x344c027d ,0x4f243231 ,12123) Jun 18 18:52:37 volumio volumio[1925]: 7: processTimers [0x4b581171] [internal/timers.js:497] [bytecode=0x280b3009 offset=144](this=0x4b580f29 ,12123) Jun 18 18:52:37 volumio volumio[1925]: 8: InternalFrame [pc: 0xec66f4] Jun 18 18:52:37 volumio volumio[1925]: 9: EntryFrame [pc: 0xec63f4] Jun 18 18:52:37 volumio volumio[1925]: ==== Details ================================================ Jun 18 18:52:37 volumio volumio[1925]: [0]: ExitFrame [pc: 0xf38000] Jun 18 18:52:37 volumio volumio[1925]: [1]: StubFrame [pc: 0xfaf400] Jun 18 18:52:37 volumio volumio[1925]: [2]: /* anonymous */(aka /* anonymous */) [0x40363ebd] [/volumio/app/plugins/system_controller/networkfs/index.js:254] [bytecode=0x53d3d121 offset=205](this=0x344c027d ,0x40366405 ) { Jun 18 18:52:37 volumio volumio[1925]: // expression stack (top to bottom) Jun 18 18:52:37 volumio volumio[1925]: [03] : 0x403664d1 Jun 18 18:52:37 volumio volumio[1925]: [02] : 0x4ff80299 Jun 18 18:52:37 volumio volumio[1925]: [01] : 0x2809b181 (sfi = 0x3f4b0615)> Jun 18 18:52:37 volumio volumio[1925]: [00] : 0x40366471 Jun 18 18:52:37 volumio volumio[1925]: --------- s o u r c e c o d e --------- Jun 18 18:52:37 volumio volumio[1925]: function (result) {\x0a if (result.error) {\x0a if (result.error.indexOf('Permission denied') >= 0) {\x0a result.error = 'Permission denied';\x0a } else {\x0a var splitreason = result.error.split('mount error');\x0a // if the split does not match, splitreason[1] is undefined\x0a ... Jun 18 18:52:37 volumio volumio[1925]: ----------------------------------------- Jun 18 18:52:37 volumio volumio[1925]: } Jun 18 18:52:37 volumio volumio[1925]: [3]: mount [0x503b13a9] [/volumio/node_modules/linux-mountutils/mountutils.js:97] [bytecode=0x53d3ba75 offset=55](this=0x39903c3d ,0x40363969 ,0x40363e5d ,0x40363ea5 ,0x40363ebd ) { Jun 18 18:52:37 volumio volumio[1925]: // heap-allocated locals Jun 18 18:52:37 volumio volumio[1925]: var callback = 0x40363ebd Jun 18 18:52:37 volumio volumio[1925]: // expression stack (top to bottom) Jun 18 18:52:37 volumio volumio[1925]: [12] : 0x40366405 Jun 18 18:52:37 volumio volumio[1925]: [11] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [10] : 0x344c03c5 Jun 18 18:52:37 volumio volumio[1925]: [09] : 0x53d3b7e5 Jun 18 18:52:37 volumio volumio[1925]: [08] : 0x40366405 Jun 18 18:52:37 volumio volumio[1925]: [07] : 0x40363ebd Jun 18 18:52:37 volumio volumio[1925]: [06] : 0x3c6f05ed Jun 18 18:52:37 volumio volumio[1925]: [05] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [04] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [03] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [02] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [01] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [00] : 0x403663dd Jun 18 18:52:37 volumio volumio[1925]: --------- s o u r c e c o d e --------- Jun 18 18:52:37 volumio volumio[1925]: function (dev, path, options, callback) {\x0a // See if there is already something mounted at the path\x0a var mountInfo = this.isMounted(path,false);\x0a if (mountInfo.mounted) {\x0a callback({"error": "Something is already mounted on " + path});\x0a return;\x0a }\x0a\x0a // See if the mountpoint exists. If not, do we ... Jun 18 18:52:37 volumio volumio[1925]: ----------------------------------------- Jun 18 18:52:37 volumio volumio[1925]: } Jun 18 18:52:37 volumio volumio[1925]: [4]: mountShare [0x53d3a071] [/volumio/app/plugins/system_controller/networkfs/index.js:239] [bytecode=0x53d3b05d offset=757](this=0x280a13bd ,0x403634f1 ) { Jun 18 18:52:37 volumio volumio[1925]: // heap-allocated locals Jun 18 18:52:37 volumio volumio[1925]: var data = 0x403634f1 Jun 18 18:52:37 volumio volumio[1925]: var self = 0x280a13bd Jun 18 18:52:37 volumio volumio[1925]: var defer = 0x40363531 Jun 18 18:52:37 volumio volumio[1925]: var trial = 3 Jun 18 18:52:37 volumio volumio[1925]: var responsemessage = 0x40366481 Jun 18 18:52:37 volumio volumio[1925]: var mountid = 0x53d3a9b1 Jun 18 18:52:37 volumio volumio[1925]: // expression stack (top to bottom) Jun 18 18:52:37 volumio volumio[1925]: [25] : 0x40363ebd Jun 18 18:52:37 volumio volumio[1925]: [24] : 0x40363ea5 Jun 18 18:52:37 volumio volumio[1925]: [23] : 0x40363e5d Jun 18 18:52:37 volumio volumio[1925]: [22] : 0x40363969 Jun 18 18:52:37 volumio volumio[1925]: [21] : 0x39903c3d Jun 18 18:52:37 volumio volumio[1925]: [20] : 0x40363ebd Jun 18 18:52:37 volumio volumio[1925]: [19] : 0x40363ea5 Jun 18 18:52:37 volumio volumio[1925]: [18] : 0x40363e5d Jun 18 18:52:37 volumio volumio[1925]: [17] : 0x40363969 Jun 18 18:52:37 volumio volumio[1925]: [16] : 0x39903c3d Jun 18 18:52:37 volumio volumio[1925]: [15] : 0x503b13a9 Jun 18 18:52:37 volumio volumio[1925]: [14] : 0x40363509 Jun 18 18:52:37 volumio volumio[1925]: [13] : 0x5b922521 Jun 18 18:52:37 volumio volumio[1925]: [12] : 0x344c03c5 Jun 18 18:52:37 volumio volumio[1925]: [11] : 0x40363e5d Jun 18 18:52:37 volumio volumio[1925]: [10] : 0x40363d39 Jun 18 18:52:37 volumio volumio[1925]: [09] : 0x40363cbd Jun 18 18:52:37 volumio volumio[1925]: [08] : 0x40363d9d Jun 18 18:52:37 volumio volumio[1925]: [07] : 0x40363e49 Jun 18 18:52:37 volumio volumio[1925]: [06] : 0x40363969 Jun 18 18:52:37 volumio volumio[1925]: [05] : 0x53d3a9b1 Jun 18 18:52:37 volumio volumio[1925]: [04] : 0x53d3a9b1 Jun 18 18:52:37 volumio volumio[1925]: [03] : 0x40363e1d Jun 18 18:52:37 volumio volumio[1925]: [02] : 0x53d3a9c5 Jun 18 18:52:37 volumio volumio[1925]: [01] : 0x40363569 Jun 18 18:52:37 volumio volumio[1925]: [00] : 0x53d3aa2d Jun 18 18:52:37 volumio volumio[1925]: --------- s o u r c e c o d e --------- Jun 18 18:52:37 volumio volumio[1925]: function (data) {\x0a var self = this;\x0a\x0a var defer = libQ.defer();\x0a var shareid = data.key;\x0a if (data.trial) {\x0a trial = data.trial;\x0a } else {\x0a var trial = 0;\x0a }\x0a\x0a var key = 'NasMounts.' + shareid;\x0a var fstype = config.get(key + '.fstype');\x0a var options = config.get(key + '.options');\x0a var path =... Jun 18 18:52:37 volumio volumio[1925]: ----------------------------------------- Jun 18 18:52:37 volumio volumio[1925]: } Jun 18 18:52:37 volumio volumio[1925]: [5]: _onTimeout [0x5960b595] [/volumio/app/plugins/system_controller/networkfs/index.js:255] [bytecode=0x3e49914d offset=34](this=0x4f74a2b9 ) { Jun 18 18:52:37 volumio volumio[1925]: // expression stack (top to bottom) Jun 18 18:52:37 volumio volumio[1925]: [05] : 0x403634f1 Jun 18 18:52:37 volumio volumio[1925]: [04] : 0x280a13bd Jun 18 18:52:37 volumio volumio[1925]: [03] : 0x5960e77d Jun 18 18:52:37 volumio volumio[1925]: [02] : 0x403634f1 Jun 18 18:52:37 volumio volumio[1925]: [01] : 0x280a13bd Jun 18 18:52:37 volumio volumio[1925]: [00] : 0x53d3a071 Jun 18 18:52:37 volumio volumio[1925]: --------- s o u r c e c o d e --------- Jun 18 18:52:37 volumio volumio[1925]: function () {\x0a self.mountShare({init: true, key: data.key, trial: trial});\x0a } Jun 18 18:52:37 volumio volumio[1925]: ----------------------------------------- Jun 18 18:52:37 volumio volumio[1925]: } Jun 18 18:52:37 volumio volumio[1925]: [6]: listOnTimeout(aka listOnTimeout) [0x5d5e6495] [internal/timers.js:554] [bytecode=0x58bde525 offset=377](this=0x344c027d ,0x4f243231 ,12123) { Jun 18 18:52:37 volumio volumio[1925]: // expression stack (top to bottom) Jun 18 18:52:37 volumio volumio[1925]: [14] : 0x4f74a2b9 Jun 18 18:52:37 volumio volumio[1925]: [13] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [12] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [11] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [10] : 0x5960b595 Jun 18 18:52:37 volumio volumio[1925]: [09] : 0x5d5e1271 Jun 18 18:52:37 volumio volumio[1925]: [08] : 0x4036346d Jun 18 18:52:37 volumio volumio[1925]: [07] : 0x5d5d9cb9 Jun 18 18:52:37 volumio volumio[1925]: [06] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [05] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [04] : 0x4036346d Jun 18 18:52:37 volumio volumio[1925]: [03] : 5001 Jun 18 18:52:37 volumio volumio[1925]: [02] : 0x4f74a2b9 Jun 18 18:52:37 volumio volumio[1925]: [01] : 0x344c035d Jun 18 18:52:37 volumio volumio[1925]: [00] : 5000 Jun 18 18:52:37 volumio volumio[1925]: --------- s o u r c e c o d e --------- Jun 18 18:52:37 volumio volumio[1925]: function listOnTimeout(list, now) {\x0a const msecs = list.msecs;\x0a\x0a debug('timeout callback %d', msecs);\x0a\x0a let ranAtLeastOneTimer = false;\x0a let timer;\x0a while (timer = L.peek(list)) {\x0a const diff = now - timer._idleStart;\x0a\x0a // Check if this loop iteration is too early for the next timer.\x0a // Thi... Jun 18 18:52:37 volumio volumio[1925]: ----------------------------------------- Jun 18 18:52:37 volumio volumio[1925]: } Jun 18 18:52:37 volumio volumio[1925]: [7]: processTimers [0x4b581171] [internal/timers.js:497] [bytecode=0x280b3009 offset=144](this=0x4b580f29 ,12123) { Jun 18 18:52:37 volumio volumio[1925]: // expression stack (top to bottom) Jun 18 18:52:37 volumio volumio[1925]: [06] : 12123 Jun 18 18:52:37 volumio volumio[1925]: [05] : 0x4f243231 Jun 18 18:52:37 volumio volumio[1925]: [04] : 0x344c027d Jun 18 18:52:37 volumio volumio[1925]: [03] : 0x5d5d9af9 Jun 18 18:52:37 volumio volumio[1925]: [02] : 0x5d5e6495 Jun 18 18:52:37 volumio volumio[1925]: [01] : 0x344c035d Jun 18 18:52:37 volumio volumio[1925]: [00] : 0x4f243231 Jun 18 18:52:37 volumio volumio[1925]: --------- s o u r c e c o d e --------- Jun 18 18:52:37 volumio volumio[1925]: function processTimers(now) {\x0a debug('process timer lists %d', now);\x0a nextExpiry = Infinity;\x0a\x0a let list;\x0a let ranAtLeastOneList = false;\x0a while (list = timerListQueue.peek()) {\x0a if (list.expiry > now) {\x0a nextExpiry = list.expiry;\x0a return refCount > 0 ? nextExpiry : -nextExpiry;\x0a }\x0a ... Jun 18 18:52:37 volumio volumio[1925]: ----------------------------------------- Jun 18 18:52:37 volumio volumio[1925]: } Jun 18 18:52:37 volumio volumio[1925]: [8]: InternalFrame [pc: 0xec66f4] Jun 18 18:52:37 volumio volumio[1925]: [9]: EntryFrame [pc: 0xec63f4] Jun 18 18:52:37 volumio volumio[1925]: ===================== Jun 18 18:52:37 volumio volumio-remote-updater[588]: [2025-06-18 18:52:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 18:52:37 volumio volumio-remote-updater[588]: [2025-06-18 18:52:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 18:52:37 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=4/ILL Jun 18 18:52:37 volumio systemd[1]: volumio.service: Failed with result 'signal'. Jun 18 18:52:37 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:37 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 18:52:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jun 18 18:52:37 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 18:52:37 volumio systemd[1]: Started Volumio Backend Module. Jun 18 18:52:37 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:37 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:38 volumio volumio[2148]: info: ------------------------------------------- Jun 18 18:52:38 volumio volumio[2148]: info: ----- Volumio3 ---- Jun 18 18:52:38 volumio volumio[2148]: info: ------------------------------------------- Jun 18 18:52:38 volumio volumio[2148]: info: ----- System startup ---- Jun 18 18:52:38 volumio volumio[2148]: info: ------------------------------------------- Jun 18 18:52:39 volumio volumio[2148]: info: MYVOLUMIO Environment detected Jun 18 18:52:39 volumio volumio[2148]: info: Plugin folders cleanup Jun 18 18:52:39 volumio volumio[2148]: info: Scanning into folder /volumio/app/plugins/ Jun 18 18:52:39 volumio volumio[2148]: info: Scanning category audio_interface Jun 18 18:52:39 volumio volumio[2148]: info: Scanning category miscellanea Jun 18 18:52:39 volumio volumio[2148]: info: Scanning category music_service Jun 18 18:52:39 volumio volumio[2148]: info: Scanning category plugins.json Jun 18 18:52:39 volumio volumio[2148]: info: Scanning category system_controller Jun 18 18:52:39 volumio volumio[2148]: info: Scanning category user_interface Jun 18 18:52:39 volumio volumio[2148]: info: Scanning into folder /data/plugins/ Jun 18 18:52:39 volumio volumio[2148]: info: Plugin folders cleanup completed Jun 18 18:52:39 volumio volumio[2148]: info: ------------------------------------------- Jun 18 18:52:39 volumio volumio[2148]: info: ----- Core plugins startup ---- Jun 18 18:52:39 volumio volumio[2148]: info: ------------------------------------------- Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 18:52:39 volumio volumio[2148]: info: Adding plugin upnp to MyMusic Plugins Jun 18 18:52:39 volumio volumio[2148]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 18:52:39 volumio volumio[2148]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugins from folder /data/plugins/ Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "system"... Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "appearance"... Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "network"... Jun 18 18:52:39 volumio volumio[2148]: info: Refreshing Cached IP Addresses Jun 18 18:52:39 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 18:52:39 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:39 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:39 volumio sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 18:52:39 volumio sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:39 volumio sudo[2177]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "services"... Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "alsa_controller"... Jun 18 18:52:39 volumio sudo[2190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 18:52:39 volumio sudo[2190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:39 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "wizard"... Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "networkfs"... Jun 18 18:52:39 volumio volumio[2148]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jun 18 18:52:39 volumio volumio[2148]: info: Starting Udev Watcher for removable devices Jun 18 18:52:39 volumio volumio[2148]: info: Ignoring mount for partition: boot Jun 18 18:52:39 volumio volumio[2148]: info: Ignoring mount for partition: volumio Jun 18 18:52:39 volumio volumio[2148]: info: Ignoring mount for partition: volumio_data Jun 18 18:52:39 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "volumio_command_line_client"... Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "upnp"... Jun 18 18:52:39 volumio volumio[2148]: info: [1750261959995] Starting Upmpd Daemon Jun 18 18:52:39 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:39 volumio volumio[2148]: info: Loading plugin "my_music"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "mpd"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "upnp_browser"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "alarm-clock"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "airplay_emulation"... Jun 18 18:52:40 volumio volumio[2148]: info: Starting Shairport Sync Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "last_100"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "webradio"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "i2s_dacs"... Jun 18 18:52:40 volumio volumio[2148]: info: I2S DAC not set, start Auto-detection Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "volumiodiscovery"... Jun 18 18:52:40 volumio volumio[2148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:40 volumio volumio[2148]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:40 volumio node[2148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:40 volumio volumio[2148]: *** WARNING *** For more information see Jun 18 18:52:40 volumio volumio[2148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:40 volumio volumio[2148]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:40 volumio volumio[2148]: *** WARNING *** For more information see Jun 18 18:52:40 volumio node[2148]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:40 volumio node[2148]: *** WARNING *** For more information see Jun 18 18:52:40 volumio node[2148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:40 volumio node[2148]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:40 volumio node[2148]: *** WARNING *** For more information see Jun 18 18:52:40 volumio volumio[2148]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 18:52:40 volumio volumio[2148]: info: Discovery: Started advertising with name: Volumio Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "outputs"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "albumart"... Jun 18 18:52:40 volumio volumio[2148]: info: Plugin example_plugin is not enabled Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "inputs"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "updater_comm"... Jun 18 18:52:40 volumio volumio[2148]: info: Plugin mpdemulation is not enabled Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "rest_api"... Jun 18 18:52:40 volumio volumio[2148]: info: Loading plugin "websocket"... Jun 18 18:52:40 volumio volumio[2148]: info: Starting Socket.io Server version 2.3.0 Jun 18 18:52:40 volumio volumio[2148]: info: Loading i18n strings for locale ru Jun 18 18:52:40 volumio volumio[2148]: Updating browse sources language Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:40 volumio volumio[2148]: Forking 3 albumart workers Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::initPlayerControls Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:40 volumio volumio[2148]: Express server listening on port 3000 Jun 18 18:52:40 volumio volumio[2148]: [Metrics] WebUI: 2s 407.99ms Jun 18 18:52:40 volumio volumio[2148]: info: CoreStateMachine::resetVolumioState Jun 18 18:52:40 volumio volumio[2148]: info: CoreStateMachine::getcurrentVolume Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:40 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:41 volumio volumio[2148]: info: Volumio Network Manager: Network status updated: 3 Jun 18 18:52:41 volumio volumio[2148]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 18 18:52:41 volumio volumio[2148]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 18:52:41 volumio volumio[2148]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 18:52:41 volumio volumio[2148]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 18 18:52:41 volumio volumio[2148]: info: Reloading queue from file Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::setRepeat false single undefined Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::pushState Jun 18 18:52:41 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::setRandom null Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::pushState Jun 18 18:52:41 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:41 volumio volumio[2148]: info: Setting Device type: Raspberry PI Jun 18 18:52:41 volumio volumio[2148]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 18 18:52:41 volumio volumio[2148]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 18 18:52:41 volumio volumio[2148]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 18 18:52:41 volumio volumio[2148]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::pushState Jun 18 18:52:41 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::updateTrackBlock Jun 18 18:52:41 volumio volumio[2148]: info: CorePlayQueue::getTrackBlock Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:41 volumio volumio[2148]: Starting albumart workers Jun 18 18:52:41 volumio volumio[2148]: Starting albumart workers Jun 18 18:52:41 volumio volumio[2148]: info: Completed loading Core Plugins Jun 18 18:52:41 volumio volumio[2148]: info: Preparing to generate the ALSA configuration file Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:41 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioGetQueue Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::getQueue Jun 18 18:52:41 volumio volumio[2148]: info: CorePlayQueue::getQueue Jun 18 18:52:41 volumio volumio[2148]: info: Listing playlists Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 18:52:41 volumio volumio[2148]: Starting albumart workers Jun 18 18:52:41 volumio volumio[2148]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:41 volumio volumio[2148]: info: CoreStateMachine::pushState Jun 18 18:52:41 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:41 volumio volumio[2148]: info: Asound.conf file unchanged, so no further update is needed Jun 18 18:52:41 volumio volumio[2148]: info: Output device has changed, restarting MPD Jun 18 18:52:41 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:41 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:41 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:41 volumio volumio[2148]: info: Output device has changed, restarting Shairport Sync Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:41 volumio sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:41 volumio sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:41 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 18:52:41 volumio volumio[2148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:41 volumio volumio[2148]: info: ___________ START PLUGINS ___________ Jun 18 18:52:41 volumio volumio[2148]: info: ControllerMpd::onStart: Initializing MPD Jun 18 18:52:41 volumio volumio[2148]: info: Creating MPD Configuration file Jun 18 18:52:41 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=11/SEGV Jun 18 18:52:41 volumio systemd[1]: mpd.service: Failed with result 'signal'. Jun 18 18:52:41 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:41 volumio volumio[2148]: info: [1750261961698] CoreMusicLibrary::Adding element Медиа-серверы Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:41 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:41 volumio sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:41 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:41 volumio sudo[2258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:41 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:41 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:41 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 18:52:41 volumio systemd[1]: mpd.service: Succeeded. Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:41 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:41 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:41 volumio volumio[2148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:41 volumio sudo[2262]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 18:52:41 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:41 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:41 volumio volumio[2148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:41 volumio volumio[2148]: info: [1750261961908] CoreMusicLibrary::Adding element Last_100 Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:41 volumio volumio[2148]: info: [1750261961911] CoreMusicLibrary::Adding element Webradio Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:41 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:41 volumio volumio[2148]: info: Initializing BBC Radios Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: Volumio Calling Home Jun 18 18:52:42 volumio volumio[2148]: info: MPD Permissions set Jun 18 18:52:42 volumio volumio[2148]: info: MPD Permissions set Jun 18 18:52:42 volumio volumio[2148]: info: Discovery: adding 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:42 volumio volumio[2148]: info: Discovery: Found device Volumio Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:42 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:42 volumio volumio[2148]: info: Discovery: this is already registered, 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:42 volumio volumio[2148]: info: Discovery: Found device Volumio Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:42 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:42 volumio volumio[2148]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 18 18:52:42 volumio volumio[2148]: info: Volumio called home Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:42 volumio volumio[2148]: info: Starting Shairport Sync Jun 18 18:52:42 volumio volumio[2148]: info: Starting Shairport Sync Jun 18 18:52:42 volumio sudo[2316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:42 volumio volumio[2148]: info: Starting Shairport Sync Jun 18 18:52:42 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:42 volumio sudo[2318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:42 volumio sudo[2318]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:42 volumio volumio[2148]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:42 volumio volumio[2148]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:42 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:42 volumio sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:42 volumio sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:42 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:42 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:42 volumio volumio[2148]: info: Shairport-Sync Started Jun 18 18:52:42 volumio volumio[2148]: Error adding Membership: Error: addMembership EINVAL Jun 18 18:52:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:42 volumio sudo[2321]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:42 volumio volumio[2148]: info: Shairport-Sync Started Jun 18 18:52:42 volumio sudo[2318]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:42 volumio volumio[2148]: info: Shairport-Sync Started Jun 18 18:52:42 volumio volumio-remote-updater[588]: [2025-06-18 18:52:42] [connect] Successful connection Jun 18 18:52:42 volumio volumio-remote-updater[588]: [2025-06-18 18:52:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750261962 101 Jun 18 18:52:42 volumio volumio[2148]: 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: 6 Jun 18 18:52:42 volumio mpd[2277]: Jun 18 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 18:52:42 volumio systemd[1]: Started Music Player Daemon. Jun 18 18:52:42 volumio sudo[2258]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:42 volumio sudo[2249]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:42 volumio volumio[2148]: info: Completed starting Core Plugins Jun 18 18:52:42 volumio volumio[2148]: info: ------------------------------------------- Jun 18 18:52:42 volumio volumio[2148]: info: ----- MyVolumio plugins startup ---- Jun 18 18:52:42 volumio volumio[2148]: info: ------------------------------------------- Jun 18 18:52:42 volumio volumio[2148]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 18:52:42 volumio volumio[2148]: error: MPD error: The expression evaluated to a falsy value: Jun 18 18:52:42 volumio volumio[2148]: assert.ok(self.idling) Jun 18 18:52:42 volumio volumio[2148]: error: The expression evaluated to a falsy value: Jun 18 18:52:42 volumio volumio[2148]: assert.ok(self.idling) Jun 18 18:52:42 volumio volumio[2148]: info: MPD running with PID2277 Jun 18 18:52:42 volumio volumio[2148]: ,establishing connection Jun 18 18:52:42 volumio volumio[2148]: error: updateQueue error: null Jun 18 18:52:42 volumio volumio[2148]: error: updateQueue error: null Jun 18 18:52:43 volumio sudo[2190]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:44 volumio volumio[2148]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Jun 18 18:52:48 volumio volumio[2148]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 18 18:52:49 volumio volumio[2148]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds Jun 18 18:52:50 volumio sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 18:52:50 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:50 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:50 volumio sudo[2352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 18:52:50 volumio sudo[2352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:50 volumio sudo[2352]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:50 volumio sudo[2355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 18 18:52:50 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:50 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:50 volumio volumio[2148]: info: Upmpdcli Daemon Started Jun 18 18:52:50 volumio volumio[2148]: # Jun 18 18:52:50 volumio volumio[2148]: # Fatal error in , line 0 Jun 18 18:52:50 volumio volumio[2148]: # unreachable code Jun 18 18:52:50 volumio volumio[2148]: # Jun 18 18:52:50 volumio volumio[2148]: #FailureMessage Object: 0xb51fc1a8 Jun 18 18:52:50 volumio volumio[2148]: Worker 3 died :( Jun 18 18:52:50 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 18 18:52:50 volumio volumio[2148]: info: CURURI: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best Jun 18 18:52:50 volumio volumio[2148]: info: Preload queue cleared Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(01) [Phil Collins] Do You Remember.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(02) [Phil Collins] One More Night.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(03) [Phil Collins] Something Happened On The Way To Heaven.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(04) [Phil Collins] In The Air Tonight.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(05) [Phil Collins] Another Day In Paradise.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(06) [Phil Collins] Sussudio.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(07) [Phil Collins] I Don't Care Anymore.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(08) [Phil Collins] This Must Be Love.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(09) [Phil Collins] I Wish It Would Rain Down.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(10) [Phil Collins] Long Long Way To Go.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(11) [Phil Collins] Heat On The Street.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(12) [Phil Collins] If Leaving Me Is Easy.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(13) [Phil Collins] Only You Know And I Know.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(14) [Phil Collins] You Can't Hurry Love.flac Jun 18 18:52:50 volumio volumio[2148]: info: Preloading song: music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(15) [Phil Collins] All Of My Life.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(01) [Phil Collins] Do You Remember.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(01)%20%5BPhil%20Collins%5D%20Do%20You%20Remember.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(01) [Phil Collins] Do You Remember.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(02) [Phil Collins] One More Night.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(02)%20%5BPhil%20Collins%5D%20One%20More%20Night.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(02) [Phil Collins] One More Night.flac Jun 18 18:52:50 volumio volumio[2148]: Starting albumart workers Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(03) [Phil Collins] Something Happened On The Way To Heaven.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(03)%20%5BPhil%20Collins%5D%20Something%20Happened%20On%20The%20Way%20To%20Heaven.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(03) [Phil Collins] Something Happened On The Way To Heaven.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(04) [Phil Collins] In The Air Tonight.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(04)%20%5BPhil%20Collins%5D%20In%20The%20Air%20Tonight.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(04) [Phil Collins] In The Air Tonight.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(05) [Phil Collins] Another Day In Paradise.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(05)%20%5BPhil%20Collins%5D%20Another%20Day%20In%20Paradise.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(05) [Phil Collins] Another Day In Paradise.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(06) [Phil Collins] Sussudio.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(06)%20%5BPhil%20Collins%5D%20Sussudio.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(06) [Phil Collins] Sussudio.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(07) [Phil Collins] I Don't Care Anymore.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(07)%20%5BPhil%20Collins%5D%20I%20Don't%20Care%20Anymore.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(07) [Phil Collins] I Don't Care Anymore.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(08) [Phil Collins] This Must Be Love.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(08)%20%5BPhil%20Collins%5D%20This%20Must%20Be%20Love.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(08) [Phil Collins] This Must Be Love.flac Jun 18 18:52:50 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(09) [Phil Collins] I Wish It Would Rain Down.flac in service mpd Jun 18 18:52:50 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(09)%20%5BPhil%20Collins%5D%20I%20Wish%20It%20Would%20Rain%20Down.flac&metadata=false Jun 18 18:52:50 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(09) [Phil Collins] I Wish It Would Rain Down.flac Jun 18 18:52:50 volumio volumio[2148]: Albumart Server FATAL ERROR: null Jun 18 18:52:51 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(10) [Phil Collins] Long Long Way To Go.flac in service mpd Jun 18 18:52:51 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(10)%20%5BPhil%20Collins%5D%20Long%20Long%20Way%20To%20Go.flac&metadata=false Jun 18 18:52:51 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(10) [Phil Collins] Long Long Way To Go.flac Jun 18 18:52:51 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(11) [Phil Collins] Heat On The Street.flac in service mpd Jun 18 18:52:51 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(11)%20%5BPhil%20Collins%5D%20Heat%20On%20The%20Street.flac&metadata=false Jun 18 18:52:51 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(11) [Phil Collins] Heat On The Street.flac Jun 18 18:52:51 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(12) [Phil Collins] If Leaving Me Is Easy.flac in service mpd Jun 18 18:52:51 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(12)%20%5BPhil%20Collins%5D%20If%20Leaving%20Me%20Is%20Easy.flac&metadata=false Jun 18 18:52:51 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(12) [Phil Collins] If Leaving Me Is Easy.flac Jun 18 18:52:51 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(13) [Phil Collins] Only You Know And I Know.flac in service mpd Jun 18 18:52:51 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(13)%20%5BPhil%20Collins%5D%20Only%20You%20Know%20And%20I%20Know.flac&metadata=false Jun 18 18:52:51 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(13) [Phil Collins] Only You Know And I Know.flac Jun 18 18:52:51 volumio volumio[2148]: info: Exploding uri music-library/NAS/Music/Phil Collins/Phil Collins - The Very Best/(14) [Phil Collins] You Can't Hurry Love.flac in service mpd Jun 18 18:52:51 volumio volumio[2148]: info: ALBUMART /albumart?cacheid=114&web=Phil%20Collins/The%20Very%20Best/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FPhil%20Collins%2FPhil%20Collins%20-%20The%20Very%20Best%2F(14)%20%5BPhil%20Collins%5D%20You%20Can't%20Hurry%20Love.flac&metadata=false Jun 18 18:52:51 volumio volumio[2148]: info: URI /mnt/NAS/Music/Phil Collins/Phil Collins - The Very Best/(14) [Phil Collins] You Can't Hurry Love.flac Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 18 18:52:51 volumio volumio[2148]: info: Adding plugin bluetooth to MyMusic Plugins Jun 18 18:52:51 volumio volumio[2148]: info: Adding plugin multiroom to MyMusic Plugins Jun 18 18:52:51 volumio volumio[2148]: info: Adding plugin metavolumio to MyMusic Plugins Jun 18 18:52:51 volumio volumio[2148]: info: Adding plugin cd_controller to MyMusic Plugins Jun 18 18:52:51 volumio volumio[2148]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 18 18:52:51 volumio volumio[2148]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 18 18:52:51 volumio volumio[2148]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 18 18:52:51 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:51 volumio volumio[2148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:51 volumio volumio[2148]: info: Starting MyVolumio Remote Streaming Endpoints Jun 18 18:52:51 volumio volumio[2148]: info: MyVolumio login type: Token Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 18 18:52:51 volumio volumio[2148]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 18 18:52:51 volumio volumio-remote-updater[588]: [2025-06-18 18:52:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 18:52:51 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Jun 18 18:52:51 volumio volumio-remote-updater[588]: [2025-06-18 18:52:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 18:52:51 volumio systemd[1]: volumio.service: Failed with result 'signal'. Jun 18 18:52:52 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:52 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:52 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 18:52:52 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jun 18 18:52:52 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 18:52:52 volumio systemd[1]: Started Volumio Backend Module. Jun 18 18:52:52 volumio systemd[1]: Started dynamicswap service. Jun 18 18:52:52 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 18:52:53 volumio volumio[2381]: info: ------------------------------------------- Jun 18 18:52:53 volumio volumio[2381]: info: ----- Volumio3 ---- Jun 18 18:52:53 volumio volumio[2381]: info: ------------------------------------------- Jun 18 18:52:53 volumio volumio[2381]: info: ----- System startup ---- Jun 18 18:52:53 volumio volumio[2381]: info: ------------------------------------------- Jun 18 18:52:53 volumio volumio[2381]: info: MYVOLUMIO Environment detected Jun 18 18:52:53 volumio volumio[2381]: info: Plugin folders cleanup Jun 18 18:52:53 volumio volumio[2381]: info: Scanning into folder /volumio/app/plugins/ Jun 18 18:52:53 volumio volumio[2381]: info: Scanning category audio_interface Jun 18 18:52:53 volumio volumio[2381]: info: Scanning category miscellanea Jun 18 18:52:53 volumio volumio[2381]: info: Scanning category music_service Jun 18 18:52:53 volumio volumio[2381]: info: Scanning category plugins.json Jun 18 18:52:53 volumio volumio[2381]: info: Scanning category system_controller Jun 18 18:52:53 volumio volumio[2381]: info: Scanning category user_interface Jun 18 18:52:53 volumio volumio[2381]: info: Scanning into folder /data/plugins/ Jun 18 18:52:53 volumio volumio[2381]: info: Plugin folders cleanup completed Jun 18 18:52:53 volumio volumio[2381]: info: ------------------------------------------- Jun 18 18:52:53 volumio volumio[2381]: info: ----- Core plugins startup ---- Jun 18 18:52:53 volumio volumio[2381]: info: ------------------------------------------- Jun 18 18:52:53 volumio volumio[2381]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 18:52:53 volumio volumio[2381]: info: Adding plugin upnp to MyMusic Plugins Jun 18 18:52:53 volumio volumio[2381]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 18:52:53 volumio volumio[2381]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 18:52:53 volumio volumio[2381]: info: Loading plugins from folder /data/plugins/ Jun 18 18:52:53 volumio volumio[2381]: info: Loading plugin "system"... Jun 18 18:52:53 volumio volumio[2381]: info: Loading plugin "appearance"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "network"... Jun 18 18:52:54 volumio volumio[2381]: info: Refreshing Cached IP Addresses Jun 18 18:52:54 volumio sudo[2408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 18:52:54 volumio sudo[2408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:54 volumio sudo[2408]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:54 volumio sudo[2410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 18:52:54 volumio sudo[2410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:54 volumio sudo[2410]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "services"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "alsa_controller"... Jun 18 18:52:54 volumio sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 18:52:54 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:54 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "wizard"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "networkfs"... Jun 18 18:52:54 volumio volumio[2381]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jun 18 18:52:54 volumio volumio[2381]: info: Starting Udev Watcher for removable devices Jun 18 18:52:54 volumio volumio[2381]: info: Ignoring mount for partition: boot Jun 18 18:52:54 volumio volumio[2381]: info: Ignoring mount for partition: volumio Jun 18 18:52:54 volumio volumio[2381]: info: Ignoring mount for partition: volumio_data Jun 18 18:52:54 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "volumio_command_line_client"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "upnp"... Jun 18 18:52:54 volumio volumio[2381]: info: [1750261974251] Starting Upmpd Daemon Jun 18 18:52:54 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "my_music"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "mpd"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "upnp_browser"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "alarm-clock"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "airplay_emulation"... Jun 18 18:52:54 volumio volumio[2381]: info: Starting Shairport Sync Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "last_100"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "webradio"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "i2s_dacs"... Jun 18 18:52:54 volumio volumio[2381]: info: I2S DAC not set, start Auto-detection Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "volumiodiscovery"... Jun 18 18:52:54 volumio volumio[2381]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:54 volumio volumio[2381]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:54 volumio node[2381]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:54 volumio volumio[2381]: *** WARNING *** For more information see Jun 18 18:52:54 volumio volumio[2381]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:54 volumio volumio[2381]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:54 volumio volumio[2381]: *** WARNING *** For more information see Jun 18 18:52:54 volumio node[2381]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:54 volumio node[2381]: *** WARNING *** For more information see Jun 18 18:52:54 volumio node[2381]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 18:52:54 volumio node[2381]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 18:52:54 volumio node[2381]: *** WARNING *** For more information see Jun 18 18:52:54 volumio volumio[2381]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 18:52:54 volumio volumio[2381]: info: Discovery: Started advertising with name: Volumio Jun 18 18:52:54 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "outputs"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "albumart"... Jun 18 18:52:54 volumio volumio[2381]: info: Plugin example_plugin is not enabled Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "inputs"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "updater_comm"... Jun 18 18:52:54 volumio volumio[2381]: info: Plugin mpdemulation is not enabled Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "rest_api"... Jun 18 18:52:54 volumio volumio[2381]: info: Loading plugin "websocket"... Jun 18 18:52:55 volumio volumio[2381]: info: Starting Socket.io Server version 2.3.0 Jun 18 18:52:55 volumio volumio[2381]: info: Loading i18n strings for locale ru Jun 18 18:52:55 volumio volumio[2381]: Updating browse sources language Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:55 volumio volumio[2381]: Forking 3 albumart workers Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::initPlayerControls Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: Express server listening on port 3000 Jun 18 18:52:55 volumio volumio[2381]: [Metrics] WebUI: 2s 433.63ms Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::resetVolumioState Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::getcurrentVolume Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: Volumio Network Manager: Network status updated: 3 Jun 18 18:52:55 volumio volumio[2381]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 18 18:52:55 volumio volumio[2381]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 18:52:55 volumio volumio[2381]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 18:52:55 volumio volumio[2381]: info: Reloading queue from file Jun 18 18:52:55 volumio volumio[2381]: verbose: New Socket.io Connection to 192.168.0.92 from 192.168.0.242 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::setRepeat false single undefined Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::pushState Jun 18 18:52:55 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::setRandom null Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::pushState Jun 18 18:52:55 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:55 volumio volumio[2381]: info: Setting Device type: Raspberry PI Jun 18 18:52:55 volumio volumio[2381]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::pushState Jun 18 18:52:55 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::updateTrackBlock Jun 18 18:52:55 volumio volumio[2381]: info: CorePlayQueue::getTrackBlock Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 18:52:55 volumio volumio[2381]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 18 18:52:55 volumio volumio[2381]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 18 18:52:55 volumio volumio[2381]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 18 18:52:55 volumio volumio[2381]: Worker 2 died :( Jun 18 18:52:55 volumio volumio[2381]: info: Completed loading Core Plugins Jun 18 18:52:55 volumio volumio[2381]: info: Preparing to generate the ALSA configuration file Jun 18 18:52:55 volumio volumio[2381]: info: VolumeController:: Volume=100 Mute =false Jun 18 18:52:55 volumio volumio[2381]: info: CoreStateMachine::pushState Jun 18 18:52:55 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::volumioPushState Jun 18 18:52:55 volumio volumio[2381]: info: Asound.conf file unchanged, so no further update is needed Jun 18 18:52:55 volumio volumio[2381]: info: Output device has changed, restarting MPD Jun 18 18:52:55 volumio volumio[2381]: Starting albumart workers Jun 18 18:52:55 volumio sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:55 volumio volumio[2381]: info: Output device has changed, restarting Shairport Sync Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:55 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:55 volumio volumio[2381]: Starting albumart workers Jun 18 18:52:55 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:55 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:55 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:55 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 18:52:55 volumio volumio[2381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:55 volumio volumio[2381]: info: ___________ START PLUGINS ___________ Jun 18 18:52:55 volumio volumio[2381]: info: ControllerMpd::onStart: Initializing MPD Jun 18 18:52:55 volumio volumio[2381]: info: Creating MPD Configuration file Jun 18 18:52:55 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=11/SEGV Jun 18 18:52:55 volumio systemd[1]: mpd.service: Failed with result 'signal'. Jun 18 18:52:55 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:55 volumio sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 18:52:55 volumio sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:55 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:55 volumio sudo[2494]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:55 volumio volumio[2381]: info: [1750261975900] CoreMusicLibrary::Adding element Медиа-серверы Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:55 volumio sudo[2497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 18:52:55 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:55 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 18:52:55 volumio systemd[1]: mpd.service: Succeeded. Jun 18 18:52:55 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 18:52:55 volumio systemd[1]: Starting Music Player Daemon... Jun 18 18:52:56 volumio volumio[2381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:56 volumio sudo[2500]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 18:52:56 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:56 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:56 volumio volumio[2381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:56 volumio volumio[2381]: info: [1750261976096] CoreMusicLibrary::Adding element Last_100 Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 18:52:56 volumio volumio[2381]: info: [1750261976099] CoreMusicLibrary::Adding element Webradio Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:56 volumio volumio[2381]: info: Initializing BBC Radios Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: Volumio Calling Home Jun 18 18:52:56 volumio volumio[2381]: Starting albumart workers Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:56 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioGetQueue Jun 18 18:52:56 volumio volumio[2381]: info: CoreStateMachine::getQueue Jun 18 18:52:56 volumio volumio[2381]: info: CorePlayQueue::getQueue Jun 18 18:52:56 volumio volumio[2381]: info: Listing playlists Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 18:52:56 volumio volumio[2381]: info: MPD Permissions set Jun 18 18:52:56 volumio volumio[2381]: info: MPD Permissions set Jun 18 18:52:56 volumio volumio[2381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: internal/cluster/utils.js:27 Jun 18 18:52:56 volumio volumio[2381]: return proc.send(message, handle); Jun 18 18:52:56 volumio volumio[2381]: ^ Jun 18 18:52:56 volumio volumio[2381]: TypeError: proc.send is not a function Jun 18 18:52:56 volumio volumio[2381]: at sendHelper (internal/cluster/utils.js:27:15) Jun 18 18:52:56 volumio volumio[2381]: at send (internal/cluster/child.js:199:10) Jun 18 18:52:56 volumio volumio[2381]: at EventEmitter.cluster._getServer (internal/cluster/child.js:98:3) Jun 18 18:52:56 volumio volumio[2381]: at listenInCluster (net.js:1379:11) Jun 18 18:52:56 volumio volumio[2381]: at Server.listen (net.js:1452:7) Jun 18 18:52:56 volumio volumio[2381]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Jun 18 18:52:56 volumio volumio[2381]: at Object. (/volumio/app/plugins/miscellanea/albumart/serverStartup.js:46:7) Jun 18 18:52:56 volumio volumio[2381]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Jun 18 18:52:56 volumio volumio[2381]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Jun 18 18:52:56 volumio volumio[2381]: at Module.load (internal/modules/cjs/loader.js:928:32) Jun 18 18:52:56 volumio volumio[2381]: node: pthread_mutex_lock.c:149: __pthread_mutex_lock: Assertion `PTHREAD_MUTEX_TYPE (mutex) == PTHREAD_MUTEX_ERRORCHECK_NP' failed. Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 18:52:56 volumio volumio[2381]: Albumart Server FATAL ERROR: null Jun 18 18:52:56 volumio volumio[2381]: info: Discovery: adding 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:56 volumio volumio[2381]: info: Discovery: Found device Volumio Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:56 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:56 volumio volumio[2381]: info: Starting Shairport Sync Jun 18 18:52:56 volumio volumio[2381]: info: Starting Shairport Sync Jun 18 18:52:56 volumio sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:56 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:56 volumio volumio[2381]: info: Starting Shairport Sync Jun 18 18:52:56 volumio sudo[2559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:56 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:56 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:56 volumio sudo[2562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 18:52:56 volumio sudo[2562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:56 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:56 volumio volumio[2381]: info: Discovery: this is already registered, 4b1566d7-20e6-43e4-bff2-ffac3d93a612 Jun 18 18:52:56 volumio volumio[2381]: info: Discovery: Found device Volumio Jun 18 18:52:56 volumio volumio[2381]: info: CoreCommandRouter::volumioGetState Jun 18 18:52:56 volumio volumio[2381]: events.js:292 Jun 18 18:52:56 volumio volumio[2381]: throw er; // Unhandled 'error' event Jun 18 18:52:56 volumio volumio[2381]: ^ Jun 18 18:52:56 volumio volumio[2381]: Error: write EPIPE Jun 18 18:52:56 volumio volumio[2381]: at process.target._send (internal/child_process.js:832:20) Jun 18 18:52:56 volumio volumio[2381]: at process.target.send (internal/child_process.js:703:19) Jun 18 18:52:56 volumio volumio[2381]: at sendHelper (internal/cluster/utils.js:27:15) Jun 18 18:52:56 volumio volumio[2381]: at send (internal/cluster/child.js:199:10) Jun 18 18:52:56 volumio volumio[2381]: at EventEmitter.cluster._getServer (internal/cluster/child.js:98:3) Jun 18 18:52:56 volumio volumio[2381]: at listenInCluster (net.js:1379:11) Jun 18 18:52:56 volumio volumio[2381]: at Server.listen (net.js:1452:7) Jun 18 18:52:56 volumio volumio[2381]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Jun 18 18:52:56 volumio volumio[2381]: at Object. (/volumio/app/plugins/miscellanea/albumart/serverStartup.js:46:7) Jun 18 18:52:56 volumio volumio[2381]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Jun 18 18:52:56 volumio volumio[2381]: Emitted 'error' event on Worker instance at: Jun 18 18:52:56 volumio volumio[2381]: at process. (internal/cluster/worker.js:30:12) Jun 18 18:52:56 volumio volumio[2381]: at process.emit (events.js:315:20) Jun 18 18:52:56 volumio volumio[2381]: at internal/child_process.js:836:39 Jun 18 18:52:56 volumio volumio[2381]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 18 18:52:56 volumio volumio[2381]: errno: -32, Jun 18 18:52:56 volumio volumio[2381]: code: 'EPIPE', Jun 18 18:52:56 volumio volumio[2381]: syscall: 'write' Jun 18 18:52:56 volumio volumio[2381]: } Jun 18 18:52:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:56 volumio volumio[2381]: info: CorePlayQueue::getTrack 0 Jun 18 18:52:56 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:56 volumio volumio[2381]: info: Shairport-Sync Started Jun 18 18:52:56 volumio volumio[2381]: Error adding Membership: Error: addMembership EINVAL Jun 18 18:52:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 18:52:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 18:52:56 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 18:52:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 18:52:56 volumio sudo[2562]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:56 volumio sudo[2559]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:56 volumio volumio[2381]: info: Shairport-Sync Started Jun 18 18:52:56 volumio volumio[2381]: info: Shairport-Sync Started Jun 18 18:52:56 volumio volumio-remote-updater[588]: [2025-06-18 18:52:56] [connect] Successful connection Jun 18 18:52:56 volumio volumio-remote-updater[588]: [2025-06-18 18:52:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750261976 101 Jun 18 18:52:56 volumio volumio[2381]: 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: 6 Jun 18 18:52:57 volumio mpd[2507]: Jun 18 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 18:52:57 volumio systemd[1]: Started Music Player Daemon. Jun 18 18:52:57 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:57 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:57 volumio volumio[2381]: info: Completed starting Core Plugins Jun 18 18:52:57 volumio volumio[2381]: info: ------------------------------------------- Jun 18 18:52:57 volumio volumio[2381]: info: ----- MyVolumio plugins startup ---- Jun 18 18:52:57 volumio volumio[2381]: info: ------------------------------------------- Jun 18 18:52:57 volumio volumio[2381]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 18:52:57 volumio volumio[2381]: error: MPD error: The expression evaluated to a falsy value: Jun 18 18:52:57 volumio volumio[2381]: assert.ok(self.idling) Jun 18 18:52:57 volumio volumio[2381]: error: The expression evaluated to a falsy value: Jun 18 18:52:57 volumio volumio[2381]: assert.ok(self.idling) Jun 18 18:52:57 volumio volumio[2381]: info: MPD running with PID2507 Jun 18 18:52:57 volumio volumio[2381]: ,establishing connection Jun 18 18:52:57 volumio volumio[2381]: error: updateQueue error: null Jun 18 18:52:57 volumio volumio[2381]: error: updateQueue error: null Jun 18 18:52:57 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root Jun 18 18:52:59 volumio volumio[2381]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Jun 18 18:53:01 volumio volumio[2381]: info: Volumio called home Jun 18 18:53:01 volumio volumio[2381]: Forking 3 albumart workers Jun 18 18:53:02 volumio volumio[2381]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 18 18:53:02 volumio volumio[2381]: Starting albumart workers Jun 18 18:53:02 volumio volumio[2381]: Starting albumart workers Jun 18 18:53:02 volumio volumio[2381]: Starting albumart workers Jun 18 18:53:02 volumio volumio[2381]: Worker 1 died :( Jun 18 18:53:03 volumio volumio[2381]: Starting albumart workers Jun 18 18:53:03 volumio volumio[2381]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 18:53:03 volumio volumio[2381]: URIError: URI malformed Jun 18 18:53:03 volumio volumio[2381]: at encodeURIComponent () Jun 18 18:53:03 volumio volumio[2381]: at AlbumArt.getAlbumArt (/volumio/app/plugins/miscellanea/albumart/index.js:135:28) Jun 18 18:53:03 volumio volumio[2381]: at ControllerMpd.getAlbumArt (/volumio/app/plugins/music_service/mpd/index.js:1848:57) Jun 18 18:53:03 volumio volumio[2381]: at /volumio/app/plugins/music_service/mpd/index.js:3021:34 Jun 18 18:53:03 volumio volumio[2381]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jun 18 18:53:03 volumio volumio[2381]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jun 18 18:53:03 volumio volumio[2381]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jun 18 18:53:03 volumio volumio[2381]: at Socket.emit (events.js:315:20) Jun 18 18:53:03 volumio volumio[2381]: at addChunk (internal/streams/readable.js:309:12) Jun 18 18:53:03 volumio volumio[2381]: at readableAddChunk (internal/streams/readable.js:280:11) Jun 18 18:53:03 volumio volumio[2381]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jun 18 18:53:03 volumio volumio[2381]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jun 18 18:53:03 volumio volumio[2381]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 18:53:03 volumio sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 18:52 Jun 18 18:53:03 volumio sudo[2643]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"