Oct 16 05:17:14 volumio ntpd[929]: CLOCK: time stepped by 29.867315 Oct 16 05:17:14 volumio ntpd[929]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 16 05:17:14 volumio sudo[1280]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 16 05:17:14 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:14 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 05:17:14 volumio volumio[1210]: info: Loading plugin "wizard"... Oct 16 05:17:14 volumio volumio[1210]: info: Loading plugin "networkfs"... Oct 16 05:17:14 volumio volumio[1210]: info: Starting Udev Watcher for removable devices Oct 16 05:17:14 volumio sudo[1304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=media_centr,password=12033zero,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.18.20.13/music /mnt/NAS/Nas Oct 16 05:17:14 volumio sudo[1304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:14 volumio volumio[1210]: info: Ignoring mount for partition: boot Oct 16 05:17:14 volumio volumio[1210]: info: Ignoring mount for partition: volumio Oct 16 05:17:14 volumio volumio[1210]: info: Ignoring mount for partition: volumio_data Oct 16 05:17:14 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 05:17:14 volumio volumio[1210]: info: Loading plugin "volumio_command_line_client"... Oct 16 05:17:14 volumio volumio[1210]: info: Loading plugin "upnp"... Oct 16 05:17:14 volumio volumio[1210]: info: [1760581034604] Starting Upmpd Daemon Oct 16 05:17:14 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 05:17:14 volumio volumio[1210]: info: Loading plugin "my_music"... Oct 16 05:17:14 volumio volumio[1210]: info: Loading plugin "mpd"... Oct 16 05:17:14 volumio kernel: netfs: FS-Cache loaded Oct 16 05:17:14 volumio kernel: Key type cifs.spnego registered Oct 16 05:17:14 volumio kernel: Key type cifs.idmap registered Oct 16 05:17:14 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 16 05:17:14 volumio kernel: CIFS: Attempting to mount //10.18.20.13/music Oct 16 05:17:15 volumio volumio[1210]: info: Loading plugin "upnp_browser"... Oct 16 05:17:15 volumio sudo[1304]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:16 volumio volumio[1210]: info: Loading plugin "alarm-clock"... Oct 16 05:17:16 volumio volumio[1210]: info: Loading plugin "airplay_emulation"... Oct 16 05:17:16 volumio volumio[1210]: info: Starting Shairport Sync Oct 16 05:17:16 volumio volumio[1210]: info: Loading plugin "last_100"... Oct 16 05:17:16 volumio volumio[1210]: info: Loading plugin "webradio"... Oct 16 05:17:17 volumio volumio[1210]: info: Loading plugin "i2s_dacs"... Oct 16 05:17:17 volumio volumio[1210]: info: Loading plugin "volumiodiscovery"... Oct 16 05:17:17 volumio volumio[1210]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 05:17:17 volumio node[1210]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 05:17:17 volumio volumio[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 05:17:17 volumio node[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 05:17:17 volumio volumio[1210]: *** WARNING *** For more information see Oct 16 05:17:17 volumio node[1210]: *** WARNING *** For more information see Oct 16 05:17:17 volumio volumio[1210]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 05:17:17 volumio node[1210]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 05:17:17 volumio volumio[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 05:17:17 volumio node[1210]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 05:17:17 volumio volumio[1210]: *** WARNING *** For more information see Oct 16 05:17:17 volumio node[1210]: *** WARNING *** For more information see Oct 16 05:17:17 volumio volumio[1210]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 16 05:17:17 volumio volumio[1210]: info: Discovery: Started advertising with name: Volumio Oct 16 05:17:17 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 05:17:17 volumio volumio[1210]: info: Loading plugin "spop"... Oct 16 05:17:18 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 16 05:17:18 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:18 volumio dhcpcd[614]: timed out Oct 16 05:17:18 volumio sh[593]: timed out Oct 16 05:17:18 volumio dhcpcd[614]: dhcpcd exited Oct 16 05:17:18 volumio sh[546]: ifup: failed to bring up eth0 Oct 16 05:17:18 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 16 05:17:18 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 16 05:17:18 volumio volumio[1210]: info: Plugin SleepWakePlugin is not enabled Oct 16 05:17:18 volumio volumio[1210]: info: Loading plugin "outputs"... Oct 16 05:17:18 volumio volumio[1210]: info: Loading plugin "albumart"... Oct 16 05:17:18 volumio volumio[1210]: info: Plugin example_plugin is not enabled Oct 16 05:17:18 volumio volumio[1210]: info: Loading plugin "inputs"... Oct 16 05:17:18 volumio volumio[1210]: info: Loading plugin "updater_comm"... Oct 16 05:17:19 volumio volumio[1210]: info: Plugin mpdemulation is not enabled Oct 16 05:17:19 volumio volumio[1210]: info: Loading plugin "rest_api"... Oct 16 05:17:19 volumio volumio[1210]: info: Loading plugin "websocket"... Oct 16 05:17:19 volumio volumio[1210]: info: Starting Socket.io Server version 1.7.4 Oct 16 05:17:19 volumio volumio[1210]: info: Plugin rpi_eeprom_config is not enabled Oct 16 05:17:19 volumio volumio[1210]: info: Loading plugin "rpi_eeprom_updater"... Oct 16 05:17:19 volumio volumio[1210]: info: Applying required configuration parameters for plugin rpi_eeprom_updater Oct 16 05:17:19 volumio volumio[1210]: info: Plugin Bluetoothremote is not enabled Oct 16 05:17:19 volumio volumio[1210]: info: Loading plugin "audiophonics_evo_sabre"... Oct 16 05:17:19 volumio volumio[1210]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Oct 16 05:17:19 volumio volumio[1344]: Forking 3 albumart workers Oct 16 05:17:20 volumio volumio[1210]: info: Loading i18n strings for locale ru Oct 16 05:17:20 volumio volumio[1210]: Updating browse sources language Oct 16 05:17:20 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 05:17:20 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::initPlayerControls Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 05:17:21 volumio volumio[1210]: Express server listening on port 3000 Oct 16 05:17:21 volumio volumio[1210]: [Metrics] WebUI: 10s 358.68ms Oct 16 05:17:21 volumio volumio[1210]: info: CoreStateMachine::resetVolumioState Oct 16 05:17:21 volumio volumio[1210]: info: CoreStateMachine::getcurrentVolume Oct 16 05:17:21 volumio volumio[1210]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 05:17:21 volumio volumio[1210]: info: Cannot read play queue from file Oct 16 05:17:21 volumio volumio[1210]: info: Volumio Network Manager: Network status updated: 2 Oct 16 05:17:22 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:22 volumio volumio[1210]: info: CoreStateMachine::setRepeat null single undefined Oct 16 05:17:22 volumio volumio[1210]: info: CoreStateMachine::pushState Oct 16 05:17:22 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:22 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 05:17:22 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Oct 16 05:17:22 volumio volumio[1210]: info: CoreStateMachine::setRandom null Oct 16 05:17:22 volumio volumio[1210]: info: CoreStateMachine::pushState Oct 16 05:17:22 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:22 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Oct 16 05:17:22 volumio volumio[1210]: info: VolumeController:: Volume=100 Mute =true Oct 16 05:17:22 volumio volumio[1210]: info: CoreStateMachine::pushState Oct 16 05:17:22 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:22 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 05:17:22 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Oct 16 05:17:22 volumio volumio[1210]: info: CoreStateMachine::updateTrackBlock Oct 16 05:17:22 volumio volumio[1210]: info: CorePlayQueue::getTrackBlock Oct 16 05:17:22 volumio volumio[1210]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 05:17:22 volumio volumio[1210]: info: Setting Device type: Raspberry PI Oct 16 05:17:22 volumio volumio[1210]: info: Completed loading Core Plugins Oct 16 05:17:22 volumio volumio[1210]: info: Preparing to generate the ALSA configuration file Oct 16 05:17:22 volumio volumio[1210]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 16 05:17:22 volumio volumio[1210]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 16 05:17:22 volumio volumio[1210]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 16 05:17:23 volumio volumio[1210]: info: VolumeController:: Volume=100 Mute =true Oct 16 05:17:23 volumio volumio[1210]: info: CoreStateMachine::pushState Oct 16 05:17:23 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::volumioPushState Oct 16 05:17:23 volumio volumio[1210]: info: Discovery: adding 6def0543-1674-4939-bd2e-b9c742839fbc Oct 16 05:17:23 volumio volumio[1210]: info: Discovery: Found device Volumio Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Oct 16 05:17:23 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:23 volumio volumio[1210]: info: Discovery: this is already registered, 6def0543-1674-4939-bd2e-b9c742839fbc Oct 16 05:17:23 volumio volumio[1210]: info: Discovery: Found device Volumio Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Oct 16 05:17:23 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:23 volumio volumio[1210]: info: Asound.conf file written Oct 16 05:17:23 volumio sudo[1405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 16 05:17:23 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:23 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:23 volumio volumio[1210]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 16 05:17:23 volumio volumio[1210]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 16 05:17:23 volumio volumio[1210]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 16 05:17:23 volumio volumio[1210]: info: Output device has changed, restarting MPD Oct 16 05:17:23 volumio volumio[1210]: info: Output device has changed, restarting Shairport Sync Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:23 volumio sudo[1411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 05:17:23 volumio sudo[1411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:23 volumio volumio[1210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 05:17:23 volumio volumio[1210]: info: ___________ START PLUGINS ___________ Oct 16 05:17:23 volumio volumio[1210]: info: ControllerMpd::onStart: Initializing MPD Oct 16 05:17:23 volumio volumio[1210]: info: Creating MPD Configuration file Oct 16 05:17:23 volumio sudo[1414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 05:17:23 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:23 volumio sudo[1411]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 05:17:23 volumio volumio[1210]: info: [1760581043681] CoreMusicLibrary::Adding element Медиа-серверы Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 05:17:23 volumio sudo[1420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 05:17:23 volumio sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:23 volumio sudo[1420]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:23 volumio sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 05:17:23 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 16 05:17:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:23 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:23 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 16 05:17:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 16 05:17:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 16 05:17:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 16 05:17:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 16 05:17:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 16 05:17:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 16 05:17:24 volumio volumio[1210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:24 volumio sudo[1432]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 05:17:24 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 05:17:24 volumio sudo[1436]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 16 05:17:24 volumio sudo[1432]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:24 volumio volumio[1210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 05:17:24 volumio volumio[1210]: info: [1760581044131] CoreMusicLibrary::Adding element Last_100 Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 05:17:24 volumio volumio[1210]: info: [1760581044133] CoreMusicLibrary::Adding element Webradio Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 05:17:24 volumio volumio[1210]: info: Initializing BBC Radios Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:24 volumio volumio[1210]: info: Creating Spotify config file Oct 16 05:17:24 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:25 volumio volumio[1210]: info: [RpiEepromUpdater] Plugin started successfully Oct 16 05:17:25 volumio volumio[1210]: info: EVO SABRE : Starting Plugin Oct 16 05:17:25 volumio volumio[1210]: info: Loading i18n strings for locale ru Oct 16 05:17:25 volumio volumio[1210]: info: Volumio Calling Home Oct 16 05:17:25 volumio volumio[1354]: Starting albumart workers Oct 16 05:17:25 volumio volumio[1355]: Starting albumart workers Oct 16 05:17:25 volumio volumio[1356]: Starting albumart workers Oct 16 05:17:28 volumio volumio-remote-updater[687]: [2025-10-16 05:17:28] [connect] Successful connection Oct 16 05:17:29 volumio mpd[1439]: 2025-10-16T05:17:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 05:17:29 volumio sudo[1507]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 05:17:29 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:29 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:29 volumio sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 05:17:29 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:29 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:29 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 16 05:17:29 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:29 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:29 volumio volumio[1210]: info: MPD Permissions set Oct 16 05:17:29 volumio volumio[1210]: info: MPD Permissions set Oct 16 05:17:29 volumio sudo[1514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 16 05:17:29 volumio volumio-remote-updater[687]: [2025-10-16 05:17:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1760581048 101 Oct 16 05:17:29 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:29 volumio systemd[1]: Reloading. Oct 16 05:17:29 volumio volumio[1210]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Oct 16 05:17:30 volumio volumio[1210]: info: Volumio called home Oct 16 05:17:30 volumio volumio[1210]: info: Spotify config file written Oct 16 05:17:30 volumio sudo[1542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 16 05:17:30 volumio sudo[1542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:30 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:30 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 05:17:30 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 16 05:17:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 05:17:30 volumio go-librespot[1544]: go-librespot daemon starting... Oct 16 05:17:31 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:31 volumio volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 16 05:17:31 volumio volumio[1210]: info: MPD running with PID1439 Oct 16 05:17:31 volumio volumio[1210]: ,establishing connection Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:31 volumio volumio[1210]: info: No need to fix Spotify hosts Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=info msg="running go-librespot 0.4.0" Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=debug msg="app state loaded" Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=debug msg="stored credentials not found" Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 16 05:17:31 volumio go-librespot[1545]: time="2025-10-16T05:17:31+03:00" level=info msg="zeroconf server listening on port 37467" Oct 16 05:17:32 volumio volumio[1210]: error: MPD error: The expression evaluated to a falsy value: Oct 16 05:17:32 volumio volumio[1210]: assert.ok(self.idling) Oct 16 05:17:32 volumio volumio[1210]: error: The expression evaluated to a falsy value: Oct 16 05:17:32 volumio volumio[1210]: assert.ok(self.idling) Oct 16 05:17:32 volumio volumio[1210]: error: MPD error: The expression evaluated to a falsy value: Oct 16 05:17:32 volumio volumio[1210]: assert.ok(self.idling) Oct 16 05:17:32 volumio volumio[1210]: error: The expression evaluated to a falsy value: Oct 16 05:17:32 volumio volumio[1210]: assert.ok(self.idling) Oct 16 05:17:32 volumio volumio[1210]: info: EVO SABRE : systemd cmd daemon-reload : success Oct 16 05:17:32 volumio sudo[1565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Oct 16 05:17:32 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:32 volumio volumio[1210]: error: updateQueue error: null Oct 16 05:17:32 volumio volumio[1210]: info: Starting Shairport Sync Oct 16 05:17:32 volumio volumio[1210]: info: Starting Shairport Sync Oct 16 05:17:32 volumio systemd[1]: Started evo_oled2.service - OLED Display Service for EVO SABRE. Oct 16 05:17:32 volumio volumio[1210]: info: Starting Shairport Sync Oct 16 05:17:32 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:32 volumio sudo[1572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 05:17:32 volumio sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:32 volumio sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 05:17:32 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 05:17:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 05:17:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 05:17:32 volumio systemd[1]: shairport-sync.service: Consumed 1.287s CPU time. Oct 16 05:17:32 volumio sudo[1584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 05:17:32 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 05:17:32 volumio sudo[1586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 05:17:32 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 05:17:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 05:17:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 05:17:32 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 05:17:32 volumio sudo[1572]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:32 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 05:17:32 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 05:17:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 05:17:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 05:17:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 05:17:32 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:33 volumio volumio[1210]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Oct 16 05:17:33 volumio sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Oct 16 05:17:33 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:33 volumio volumio[1210]: info: Shairport-Sync Started Oct 16 05:17:33 volumio volumio[1210]: Error adding Membership: Error: addMembership EINVAL Oct 16 05:17:33 volumio volumio[1210]: info: Upmpdcli Daemon Started Oct 16 05:17:33 volumio volumio[1210]: info: Shairport-Sync Started Oct 16 05:17:33 volumio sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 16 05:17:33 volumio volumio[1210]: info: Shairport-Sync Started Oct 16 05:17:33 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:33 volumio sudo[1637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 16 05:17:33 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:33 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 16 05:17:33 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Oct 16 05:17:33 volumio systemd[1]: Started evo_remote.service. Oct 16 05:17:33 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:33 volumio (lircd)[1643]: evo_remote.service: Failed to locate executable /usr/sbin/lircd: No such file or directory Oct 16 05:17:33 volumio (lircd)[1643]: evo_remote.service: Failed at step EXEC spawning /usr/sbin/lircd: No such file or directory Oct 16 05:17:33 volumio systemd[1]: evo_remote.service: Main process exited, code=exited, status=203/EXEC Oct 16 05:17:33 volumio systemd[1]: evo_remote.service: Failed with result 'exit-code'. Oct 16 05:17:33 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:33 volumio volumio[1210]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Oct 16 05:17:33 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 16 05:17:33 volumio mpd_monitor.sh[1657]: MPD Monitor Service: Starting MPD Monitor Service Oct 16 05:17:33 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 16 05:17:33 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:33 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 16 05:17:33 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 16 05:17:33 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 16 05:17:33 volumio mpd_monitor.sh[1665]: MPD Monitor Service: Starting MPD Monitor Service Oct 16 05:17:33 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:33 volumio sudo[1658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Oct 16 05:17:33 volumio sudo[1658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:17:34 volumio volumio[1210]: info: Successfully started MPD Monitor Oct 16 05:17:34 volumio volumio[1210]: info: Successfully started MPD Monitor Oct 16 05:17:34 volumio systemd[1]: Started evo_irexec.service. Oct 16 05:17:34 volumio (irexec)[1672]: evo_irexec.service: Failed to locate executable /usr/bin/irexec: No such file or directory Oct 16 05:17:34 volumio (irexec)[1672]: evo_irexec.service: Failed at step EXEC spawning /usr/bin/irexec: No such file or directory Oct 16 05:17:34 volumio systemd[1]: evo_irexec.service: Main process exited, code=exited, status=203/EXEC Oct 16 05:17:34 volumio systemd[1]: evo_irexec.service: Failed with result 'exit-code'. Oct 16 05:17:34 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root Oct 16 05:17:34 volumio volumio[1210]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Oct 16 05:17:34 volumio volumio[1210]: info: Completed starting Core Plugins Oct 16 05:17:34 volumio volumio[1210]: info: ------------------------------------------- Oct 16 05:17:34 volumio volumio[1210]: info: ----- MyVolumio plugins startup ---- Oct 16 05:17:34 volumio volumio[1210]: info: ------------------------------------------- Oct 16 05:17:34 volumio volumio[1210]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 16 05:17:34 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 16 05:17:34 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 16 05:17:35 volumio volumio[1210]: info: go-librespot daemon successfully initialized Oct 16 05:17:37 volumio volumio[1210]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 16 05:17:38 volumio volumio[1210]: info: Initializing connection to go-librespot Websocket Oct 16 05:17:39 volumio go-librespot[1545]: time="2025-10-16T05:17:39+03:00" level=debug msg="new websocket client" Oct 16 05:17:41 volumio volumio[1210]: info: Connection to go-librespot Websocket established Oct 16 05:17:42 volumio volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 16 05:17:43 volumio upmpdcli[1719]: writing RSA key Oct 16 05:17:47 volumio volumio[1210]: info: Getting Spotify volume Oct 16 05:17:50 volumio volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 16 05:17:51 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Oct 16 05:17:51 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:51 volumio volumio[1210]: info: CoreCommandRouter::volumioGetQueue Oct 16 05:17:51 volumio volumio[1210]: info: CoreStateMachine::getQueue Oct 16 05:17:51 volumio volumio[1210]: info: CorePlayQueue::getQueue Oct 16 05:17:55 volumio volumio[1210]: info: CoreCommandRouter::volumioGetState Oct 16 05:17:55 volumio volumio[1210]: info: CorePlayQueue::getTrack 0 Oct 16 05:17:56 volumio volumio[1210]: error: MyVolumio Plugin failed to start in a timely fashion Oct 16 05:17:56 volumio volumio[1210]: [Metrics] CommandRouter: 44s 278.88ms Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::volumiosetStartupVolume Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::Close All Modals sent Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::Close All Modals sent Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:17:56 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 05:17:57 volumio volumio[1210]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 05:17:57 volumio volumio[1210]: info: Adding plugin multiroom to MyMusic Plugins Oct 16 05:17:57 volumio volumio[1210]: info: Adding plugin metavolumio to MyMusic Plugins Oct 16 05:17:57 volumio volumio[1210]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 05:17:57 volumio volumio[1210]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 16 05:17:57 volumio volumio[1210]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 05:17:57 volumio volumio[1210]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 05:17:57 volumio volumio[1210]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 05:18:03 volumio volumio[1210]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 05:18:03 volumio volumio[1210]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 05:18:03 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:18:03 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 05:18:03 volumio volumio[1210]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 05:18:03 volumio volumio[1210]: info: MyVolumio login type: Token Oct 16 05:18:03 volumio volumio[1210]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 05:18:03 volumio volumio[1210]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 05:18:08 volumio volumio[1210]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 05:18:08 volumio volumio[1210]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 05:18:08 volumio volumio[1210]: info: Streaming services startup Oct 16 05:18:08 volumio volumio[1210]: info: Starting Streaming Daemon Oct 16 05:18:08 volumio volumio[1210]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 16 05:18:08 volumio sudo[1799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 05:18:08 volumio sudo[1799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 05:18:08 volumio sudo[1799]: pam_unix(sudo:session): session closed for user root Oct 16 05:18:08 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 16 05:18:08 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 05:18:08 volumio volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 16 05:18:08 volumio volumio[1210]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 05:18:08 volumio volumio[1210]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 16 05:18:08 volumio volumio[1210]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 16 05:18:08 volumio volumio[1210]: at new NodeError (node:internal/errors:405:5) Oct 16 05:18:08 volumio volumio[1210]: at assert (node:internal/assert:14:11) Oct 16 05:18:08 volumio volumio[1210]: at internalConnectMultiple (node:net:1118:3) Oct 16 05:18:08 volumio volumio[1210]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 16 05:18:08 volumio volumio[1210]: at listOnTimeout (node:internal/timers:575:11) Oct 16 05:18:08 volumio volumio[1210]: at process.processTimers (node:internal/timers:514:7) { Oct 16 05:18:08 volumio volumio[1210]: code: 'ERR_INTERNAL_ASSERTION' Oct 16 05:18:08 volumio volumio[1210]: } Oct 16 05:18:08 volumio volumio[1210]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 05:18:13 volumio sudo[1819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-16 05:17' Oct 16 05:18:13 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"