Dec 06 16:22:42 phvolumio ntpd[894]: CLOCK: time stepped by 61354.633106 Dec 06 16:22:42 phvolumio ntpd[894]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 06 16:22:42 phvolumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 06 16:22:42 phvolumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 06 16:22:42 phvolumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 06 16:22:43 phvolumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 06 16:22:43 phvolumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 06 16:22:43 phvolumio volumio[1204]: info: MYVOLUMIO Environment detected Dec 06 16:22:43 phvolumio volumio[1204]: info: Plugin folders cleanup Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning into folder /volumio/app/plugins/ Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning category audio_interface Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning category miscellanea Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning category music_service Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning category plugins.json Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning category system_controller Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning category user_interface Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning into folder /data/plugins/ Dec 06 16:22:43 phvolumio volumio[1204]: info: Scanning category music_service Dec 06 16:22:43 phvolumio volumio[1204]: info: Plugin folders cleanup completed Dec 06 16:22:43 phvolumio volumio[1204]: info: ------------------------------------------- Dec 06 16:22:43 phvolumio volumio[1204]: info: ----- Core plugins startup ---- Dec 06 16:22:43 phvolumio volumio[1204]: info: ------------------------------------------- Dec 06 16:22:43 phvolumio volumio[1204]: info: Loading plugins from folder /volumio/app/plugins/ Dec 06 16:22:43 phvolumio volumio[1204]: info: Adding plugin upnp to MyMusic Plugins Dec 06 16:22:43 phvolumio volumio[1204]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 06 16:22:43 phvolumio volumio[1204]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 06 16:22:43 phvolumio volumio[1204]: info: Loading plugins from folder /data/plugins/ Dec 06 16:22:43 phvolumio volumio[1204]: info: Loading plugin "system"... Dec 06 16:22:43 phvolumio volumio[1204]: info: Loading plugin "appearance"... Dec 06 16:22:45 phvolumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 06 16:22:45 phvolumio volumio[1204]: info: Loading plugin "network"... Dec 06 16:22:45 phvolumio volumio[1204]: info: Refreshing Cached IP Addresses Dec 06 16:22:45 phvolumio sudo[1307]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 16:22:45 phvolumio sudo[1307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:45 phvolumio sudo[1308]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 16:22:45 phvolumio sudo[1308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:45 phvolumio sudo[1307]: pam_unix(sudo:session): session closed for user root Dec 06 16:22:45 phvolumio volumio[1204]: info: Loading plugin "services"... Dec 06 16:22:45 phvolumio sudo[1308]: pam_unix(sudo:session): session closed for user root Dec 06 16:22:45 phvolumio volumio[1204]: info: Loading plugin "alsa_controller"... Dec 06 16:22:45 phvolumio sudo[1311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 06 16:22:45 phvolumio sudo[1311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:45 phvolumio sh[624]: timed out Dec 06 16:22:45 phvolumio dhcpcd[634]: timed out Dec 06 16:22:46 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 16:22:46 phvolumio volumio[1204]: info: Loading plugin "wizard"... Dec 06 16:22:46 phvolumio sh[569]: ifup: failed to bring up eth0 Dec 06 16:22:46 phvolumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 06 16:22:46 phvolumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 06 16:22:46 phvolumio volumio[1204]: info: Loading plugin "networkfs"... Dec 06 16:22:46 phvolumio volumio[1204]: info: Starting Udev Watcher for removable devices Dec 06 16:22:46 phvolumio sudo[1341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=manager,password=Hansen01Transfer!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.23/Musik /mnt/NAS/phnas Dec 06 16:22:46 phvolumio sudo[1341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:46 phvolumio volumio[1204]: info: Ignoring mount for partition: boot Dec 06 16:22:46 phvolumio volumio[1204]: info: Ignoring mount for partition: volumio Dec 06 16:22:46 phvolumio volumio[1204]: info: Ignoring mount for partition: volumio_data Dec 06 16:22:46 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 16:22:46 phvolumio volumio[1204]: info: Loading plugin "volumio_command_line_client"... Dec 06 16:22:46 phvolumio volumio[1204]: info: Loading plugin "upnp"... Dec 06 16:22:46 phvolumio volumio[1204]: info: [1765034566263] Starting Upmpd Daemon Dec 06 16:22:46 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 16:22:46 phvolumio volumio[1204]: info: Loading plugin "my_music"... Dec 06 16:22:46 phvolumio volumio[1204]: info: Loading plugin "mpd"... Dec 06 16:22:46 phvolumio kernel: netfs: FS-Cache loaded Dec 06 16:22:46 phvolumio kernel: Key type cifs.spnego registered Dec 06 16:22:46 phvolumio kernel: Key type cifs.idmap registered Dec 06 16:22:46 phvolumio 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. Dec 06 16:22:46 phvolumio kernel: CIFS: Attempting to mount //192.168.1.23/Musik Dec 06 16:22:46 phvolumio volumio[1204]: info: Plugin upnp_browser is not enabled Dec 06 16:22:46 phvolumio volumio[1204]: info: Loading plugin "alarm-clock"... Dec 06 16:22:47 phvolumio volumio[1204]: info: Plugin airplay_emulation is not enabled Dec 06 16:22:47 phvolumio volumio[1204]: info: Loading plugin "last_100"... Dec 06 16:22:47 phvolumio volumio[1204]: info: Loading plugin "webradio"... Dec 06 16:22:49 phvolumio volumio[1204]: info: Loading plugin "i2s_dacs"... Dec 06 16:22:49 phvolumio volumio[1204]: info: Loading plugin "volumiodiscovery"... Dec 06 16:22:49 phvolumio volumio[1204]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 06 16:22:49 phvolumio volumio[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 16:22:49 phvolumio volumio[1204]: *** WARNING *** For more information see Dec 06 16:22:49 phvolumio volumio[1204]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 06 16:22:49 phvolumio volumio[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 16:22:49 phvolumio volumio[1204]: *** WARNING *** For more information see Dec 06 16:22:49 phvolumio node[1204]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 06 16:22:49 phvolumio node[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 16:22:49 phvolumio node[1204]: *** WARNING *** For more information see Dec 06 16:22:49 phvolumio node[1204]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 06 16:22:49 phvolumio node[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 16:22:49 phvolumio node[1204]: *** WARNING *** For more information see Dec 06 16:22:49 phvolumio volumio[1204]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 06 16:22:49 phvolumio volumio[1204]: info: Discovery: Started advertising with name: PHVolumio Dec 06 16:22:49 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 16:22:49 phvolumio volumio[1204]: info: Plugin spop is not enabled Dec 06 16:22:49 phvolumio volumio[1204]: info: Loading plugin "outputs"... Dec 06 16:22:49 phvolumio volumio[1204]: info: Loading plugin "albumart"... Dec 06 16:22:49 phvolumio volumio[1204]: info: Plugin example_plugin is not enabled Dec 06 16:22:49 phvolumio volumio[1204]: info: Loading plugin "inputs"... Dec 06 16:22:49 phvolumio volumio[1204]: info: Loading plugin "updater_comm"... Dec 06 16:22:49 phvolumio sudo[1311]: pam_unix(sudo:session): session closed for user root Dec 06 16:22:49 phvolumio volumio[1204]: info: Plugin mpdemulation is not enabled Dec 06 16:22:49 phvolumio volumio[1204]: info: Loading plugin "rest_api"... Dec 06 16:22:50 phvolumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 06 16:22:50 phvolumio volumio[1204]: info: Loading plugin "websocket"... Dec 06 16:22:50 phvolumio volumio[1204]: info: Starting Socket.io Server version 1.7.4 Dec 06 16:22:50 phvolumio volumio[1204]: info: Loading i18n strings for locale en Dec 06 16:22:50 phvolumio volumio[1204]: Updating browse sources language Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 16:22:50 phvolumio volumio-remote-updater[702]: [2025-12-06 16:22:50] [connect] Successful connection Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::initPlayerControls Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:22:50 phvolumio volumio[1204]: Express server listening on port 3000 Dec 06 16:22:50 phvolumio volumio[1204]: [Metrics] WebUI: 10s 32.23ms Dec 06 16:22:51 phvolumio volumio[1204]: info: CoreStateMachine::resetVolumioState Dec 06 16:22:51 phvolumio volumio[1204]: info: CoreStateMachine::getcurrentVolume Dec 06 16:22:51 phvolumio volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 16:22:51 phvolumio volumio[1366]: Forking 3 albumart workers Dec 06 16:22:51 phvolumio volumio[1204]: info: Volumio Network Manager: Network status updated: 2 Dec 06 16:22:51 phvolumio volumio[1204]: info: CoreCommandRouter::volumioGetState Dec 06 16:22:51 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:22:52 phvolumio volumio-remote-updater[702]: [2025-12-06 16:22:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765034570 101 Dec 06 16:22:52 phvolumio volumio[1204]: 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 Dec 06 16:22:52 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:22:52 phvolumio volumio[1204]: info: VolumeController:: Volume=100 Mute =false Dec 06 16:22:52 phvolumio volumio[1204]: info: CoreStateMachine::pushState Dec 06 16:22:52 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:22:52 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 16:22:52 phvolumio volumio[1204]: info: CoreCommandRouter::volumioPushState Dec 06 16:22:52 phvolumio volumio[1204]: info: CoreStateMachine::updateTrackBlock Dec 06 16:22:52 phvolumio volumio[1204]: info: CorePlayQueue::getTrackBlock Dec 06 16:22:52 phvolumio volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreStateMachine::setRepeat null single undefined Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreStateMachine::pushState Dec 06 16:22:53 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreCommandRouter::volumioPushState Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreStateMachine::setRandom null Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreStateMachine::pushState Dec 06 16:22:53 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreCommandRouter::volumioPushState Dec 06 16:22:53 phvolumio volumio[1204]: info: Setting Device type: Raspberry PI Dec 06 16:22:53 phvolumio volumio[1204]: info: Getting Push Notification urls Dec 06 16:22:53 phvolumio volumio[1204]: info: Discovery: adding a59c3381-a5fe-49f7-aa87-bfed9151a5e5 Dec 06 16:22:53 phvolumio volumio[1204]: info: Discovery: Found device PHVolumio Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreCommandRouter::volumioGetState Dec 06 16:22:53 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:22:53 phvolumio volumio[1204]: info: Discovery: this is already registered, a59c3381-a5fe-49f7-aa87-bfed9151a5e5 Dec 06 16:22:53 phvolumio volumio[1204]: info: Discovery: Found device PHVolumio Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreCommandRouter::volumioGetState Dec 06 16:22:53 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:22:53 phvolumio volumio[1204]: info: VolumeController:: Volume=100 Mute =false Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreStateMachine::pushState Dec 06 16:22:53 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:22:53 phvolumio volumio[1204]: info: CoreCommandRouter::volumioPushState Dec 06 16:22:54 phvolumio volumio[1204]: info: Adding Push Notification url Dec 06 16:22:56 phvolumio sudo[1441]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 16:22:56 phvolumio sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:56 phvolumio sudo[1441]: pam_unix(sudo:session): session closed for user root Dec 06 16:22:56 phvolumio sudo[1443]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 16:22:56 phvolumio sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:56 phvolumio sudo[1443]: pam_unix(sudo:session): session closed for user root Dec 06 16:22:56 phvolumio sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 06 16:22:56 phvolumio sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:56 phvolumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 06 16:22:56 phvolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 06 16:22:56 phvolumio sudo[1447]: pam_unix(sudo:session): session closed for user root Dec 06 16:22:56 phvolumio volumio[1204]: info: Upmpdcli Daemon Started Dec 06 16:22:56 phvolumio volumio[1204]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Dec 06 16:22:58 phvolumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 06 16:22:58 phvolumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 06 16:22:59 phvolumio sudo[1341]: pam_unix(sudo:session): session closed for user root Dec 06 16:22:59 phvolumio volumio[1204]: info: Completed loading Core Plugins Dec 06 16:22:59 phvolumio volumio[1204]: info: Preparing to generate the ALSA configuration file Dec 06 16:22:59 phvolumio volumio[1204]: info: Asound.conf file unchanged, so no further update is needed Dec 06 16:22:59 phvolumio volumio[1204]: info: Output device has changed, restarting MPD Dec 06 16:22:59 phvolumio volumio[1384]: Starting albumart workers Dec 06 16:22:59 phvolumio volumio[1204]: info: ___________ START PLUGINS ___________ Dec 06 16:22:59 phvolumio volumio[1204]: info: ControllerMpd::onStart: Initializing MPD Dec 06 16:22:59 phvolumio volumio[1204]: info: Creating MPD Configuration file Dec 06 16:22:59 phvolumio sudo[1503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 16:22:59 phvolumio sudo[1503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:22:59 phvolumio volumio[1386]: Starting albumart workers Dec 06 16:22:59 phvolumio volumio[1385]: Starting albumart workers Dec 06 16:22:59 phvolumio volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 16:22:59 phvolumio volumio[1204]: info: [1765034579973] CoreMusicLibrary::Adding element Last_100 Dec 06 16:22:59 phvolumio sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 16:22:59 phvolumio sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:23:00 phvolumio sudo[1503]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:00 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 16:23:00 phvolumio volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 16:23:00 phvolumio volumio[1204]: info: [1765034580046] CoreMusicLibrary::Adding element Webradio Dec 06 16:23:00 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 16:23:00 phvolumio sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 16:23:00 phvolumio sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:23:00 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 16:23:00 phvolumio sudo[1508]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:00 phvolumio sudo[1510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 16:23:00 phvolumio sudo[1510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:23:00 phvolumio volumio[1204]: info: Initializing BBC Radios Dec 06 16:23:00 phvolumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 16:23:00 phvolumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 16:23:00 phvolumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 16:23:00 phvolumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 16:23:00 phvolumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 16:23:00 phvolumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 16:23:00 phvolumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 16:23:00 phvolumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 16:23:00 phvolumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 16:23:00 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 16:23:00 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:23:00 phvolumio volumio[1204]: info: Volumio Calling Home Dec 06 16:23:00 phvolumio sudo[1525]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 16:23:00 phvolumio sudo[1525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 16:23:00 phvolumio sudo[1531]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 06 16:23:00 phvolumio sudo[1525]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:06 phvolumio volumio[1204]: info: MPD Permissions set Dec 06 16:23:06 phvolumio volumio[1204]: info: MPD Permissions set Dec 06 16:23:06 phvolumio volumio[1204]: info: Volumio called home Dec 06 16:23:07 phvolumio volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:23:07 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:07 phvolumio mpd[1532]: 2025-12-06T16:23:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 16:23:07 phvolumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 16:23:07 phvolumio sudo[1510]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:07 phvolumio sudo[1505]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:08 phvolumio volumio[1204]: info: Completed starting Core Plugins Dec 06 16:23:08 phvolumio volumio[1204]: info: ------------------------------------------- Dec 06 16:23:08 phvolumio volumio[1204]: info: ----- MyVolumio plugins startup ---- Dec 06 16:23:08 phvolumio volumio[1204]: info: ------------------------------------------- Dec 06 16:23:08 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 06 16:23:08 phvolumio volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Dec 06 16:23:08 phvolumio volumio[1204]: assert.ok(self.idling) Dec 06 16:23:08 phvolumio volumio[1204]: error: The expression evaluated to a falsy value: Dec 06 16:23:08 phvolumio volumio[1204]: assert.ok(self.idling) Dec 06 16:23:08 phvolumio volumio[1204]: error: updateQueue error: null Dec 06 16:23:08 phvolumio volumio[1204]: info: MPD running with PID1532 Dec 06 16:23:08 phvolumio volumio[1204]: ,establishing connection Dec 06 16:23:09 phvolumio volumio[1204]: error: updateQueue error: null Dec 06 16:23:09 phvolumio volumio[1204]: info: CoreCommandRouter::volumioGetState Dec 06 16:23:09 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:23:09 phvolumio volumio[1204]: info: CoreCommandRouter::volumioGetState Dec 06 16:23:09 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:23:11 phvolumio sudo[1592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 16:23:11 phvolumio sudo[1592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:23:11 phvolumio sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 16:23:11 phvolumio sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:23:11 phvolumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 16:23:11 phvolumio sudo[1592]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:11 phvolumio mpd_monitor.sh[1596]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 16:23:11 phvolumio volumio[1204]: info: Successfully started MPD Monitor Dec 06 16:23:11 phvolumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 16:23:11 phvolumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 16:23:11 phvolumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 16:23:11 phvolumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 16:23:11 phvolumio mpd_monitor.sh[1601]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 16:23:11 phvolumio sudo[1594]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:11 phvolumio volumio[1204]: info: Successfully started MPD Monitor Dec 06 16:23:13 phvolumio volumio[1204]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 06 16:23:28 phvolumio volumio[1204]: info: CoreCommandRouter::volumioGetState Dec 06 16:23:28 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:23:29 phvolumio volumio[1204]: error: MyVolumio Plugin failed to start in a timely fashion Dec 06 16:23:29 phvolumio volumio[1204]: [Metrics] CommandRouter: 47s 120.13ms Dec 06 16:23:29 phvolumio volumio[1204]: info: CoreCommandRouter::volumiosetStartupVolume Dec 06 16:23:29 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:23:29 phvolumio volumio[1204]: info: VolumeController:: Setting startup Volume 100 Dec 06 16:23:29 phvolumio volumio[1204]: info: VolumeController::SetAlsaVolume100 Dec 06 16:23:29 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:23:29 phvolumio volumio[1204]: info: CoreCommandRouter::Close All Modals sent Dec 06 16:23:29 phvolumio volumio[1204]: info: CoreCommandRouter::Close All Modals sent Dec 06 16:23:29 phvolumio volumio[1204]: info: CoreStateMachine::pushState Dec 06 16:23:29 phvolumio volumio[1204]: info: CorePlayQueue::getTrack 0 Dec 06 16:23:29 phvolumio volumio[1204]: info: CoreCommandRouter::volumioPushState Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 06 16:23:34 phvolumio volumio[1204]: info: Adding plugin bluetooth to MyMusic Plugins Dec 06 16:23:34 phvolumio volumio[1204]: info: Adding plugin multiroom to MyMusic Plugins Dec 06 16:23:34 phvolumio volumio[1204]: info: Adding plugin metavolumio to MyMusic Plugins Dec 06 16:23:34 phvolumio volumio[1204]: info: Adding plugin cd_controller to MyMusic Plugins Dec 06 16:23:34 phvolumio volumio[1204]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 06 16:23:34 phvolumio volumio[1204]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 06 16:23:34 phvolumio volumio[1204]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 06 16:23:34 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 06 16:23:35 phvolumio upmpdcli[1639]: writing RSA key Dec 06 16:23:40 phvolumio volumio[1204]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 06 16:23:40 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 06 16:23:40 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:23:40 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:23:40 phvolumio volumio[1204]: info: Starting MyVolumio Remote Streaming Endpoints Dec 06 16:23:41 phvolumio volumio[1204]: info: MyVolumio login type: Token Dec 06 16:23:41 phvolumio volumio[1204]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 06 16:23:41 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 06 16:23:47 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 06 16:23:47 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 06 16:23:47 phvolumio volumio[1204]: info: Streaming services startup Dec 06 16:23:47 phvolumio volumio[1204]: info: Starting Streaming Daemon Dec 06 16:23:47 phvolumio volumio[1204]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 06 16:23:47 phvolumio sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 06 16:23:47 phvolumio sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:23:48 phvolumio sudo[1672]: pam_unix(sudo:session): session closed for user root Dec 06 16:23:48 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 06 16:23:48 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 16:23:48 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 06 16:23:48 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 16:23:48 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:23:48 phvolumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 16:23:48 phvolumio volumio[1204]: info: BOOT COMPLETED Dec 06 16:23:48 phvolumio volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 16:23:48 phvolumio volumio[1204]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 06 16:23:48 phvolumio volumio[1204]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 06 16:23:48 phvolumio volumio[1204]: at new NodeError (node:internal/errors:405:5) Dec 06 16:23:48 phvolumio volumio[1204]: at assert (node:internal/assert:14:11) Dec 06 16:23:48 phvolumio volumio[1204]: at internalConnectMultiple (node:net:1118:3) Dec 06 16:23:48 phvolumio volumio[1204]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 06 16:23:48 phvolumio volumio[1204]: at listOnTimeout (node:internal/timers:575:11) Dec 06 16:23:48 phvolumio volumio[1204]: at process.processTimers (node:internal/timers:514:7) { Dec 06 16:23:48 phvolumio volumio[1204]: code: 'ERR_INTERNAL_ASSERTION' Dec 06 16:23:48 phvolumio volumio[1204]: } Dec 06 16:23:48 phvolumio volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 16:23:53 phvolumio sudo[1698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 16:22' Dec 06 16:23:53 phvolumio sudo[1698]: 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"