Dec 13 19:09:28 arbeitszimmer-papa ntpd[988]: CLOCK: time stepped by 2585093.770231 Dec 13 19:09:28 arbeitszimmer-papa ntpd[988]: CLOCK: time changed from 2025-11-13 to 2025-12-13 Dec 13 19:09:28 arbeitszimmer-papa ntpd[988]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "network"... Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Refreshing Cached IP Addresses Dec 13 19:09:29 arbeitszimmer-papa sudo[1323]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 13 19:09:29 arbeitszimmer-papa sudo[1323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:29 arbeitszimmer-papa sudo[1320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 13 19:09:29 arbeitszimmer-papa sudo[1320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:29 arbeitszimmer-papa sudo[1323]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "services"... Dec 13 19:09:29 arbeitszimmer-papa sudo[1320]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "alsa_controller"... Dec 13 19:09:29 arbeitszimmer-papa sudo[1325]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 13 19:09:29 arbeitszimmer-papa sudo[1325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: warn: Unable to locate the audio output device DigiHug USB Audio. Please configure a valid output device. Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "wizard"... Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "networkfs"... Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Starting Udev Watcher for removable devices Dec 13 19:09:29 arbeitszimmer-papa sudo[1351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=karsten,password=Ibe1978GJK,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //Srv-DS/music /mnt/NAS/Srv-DS Dec 13 19:09:29 arbeitszimmer-papa sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Ignoring mount for partition: boot Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Ignoring mount for partition: volumio Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Ignoring mount for partition: volumio_data Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "volumio_command_line_client"... Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "upnp"... Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: [1765649369664] Starting Upmpd Daemon Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "my_music"... Dec 13 19:09:29 arbeitszimmer-papa volumio[1258]: info: Loading plugin "mpd"... Dec 13 19:09:29 arbeitszimmer-papa kernel: netfs: FS-Cache loaded Dec 13 19:09:29 arbeitszimmer-papa kernel: Key type cifs.spnego registered Dec 13 19:09:29 arbeitszimmer-papa kernel: Key type cifs.idmap registered Dec 13 19:09:29 arbeitszimmer-papa 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 13 19:09:29 arbeitszimmer-papa kernel: CIFS: Attempting to mount //Srv-DS/music Dec 13 19:09:30 arbeitszimmer-papa volumio[1258]: info: Loading plugin "upnp_browser"... Dec 13 19:09:30 arbeitszimmer-papa sudo[1351]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:31 arbeitszimmer-papa systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 13 19:09:31 arbeitszimmer-papa systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 13 19:09:31 arbeitszimmer-papa systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 13 19:09:31 arbeitszimmer-papa systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 13 19:09:31 arbeitszimmer-papa systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 13 19:09:31 arbeitszimmer-papa volumio[1258]: info: Loading plugin "alarm-clock"... Dec 13 19:09:31 arbeitszimmer-papa volumio[1258]: info: Loading plugin "airplay_emulation"... Dec 13 19:09:31 arbeitszimmer-papa volumio[1258]: info: Starting Shairport Sync Dec 13 19:09:31 arbeitszimmer-papa volumio[1258]: info: Loading plugin "last_100"... Dec 13 19:09:31 arbeitszimmer-papa volumio[1258]: info: Loading plugin "webradio"... Dec 13 19:09:31 arbeitszimmer-papa systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: info: Loading plugin "i2s_dacs"... Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: info: I2S DAC not set, start Auto-detection Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: info: Loading plugin "volumiodiscovery"... Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 13 19:09:32 arbeitszimmer-papa node[1258]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 13 19:09:32 arbeitszimmer-papa node[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: *** WARNING *** For more information see Dec 13 19:09:32 arbeitszimmer-papa node[1258]: *** WARNING *** For more information see Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 13 19:09:32 arbeitszimmer-papa node[1258]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 13 19:09:32 arbeitszimmer-papa node[1258]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: *** WARNING *** For more information see Dec 13 19:09:32 arbeitszimmer-papa node[1258]: *** WARNING *** For more information see Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: info: Discovery: Started advertising with name: Arbeitszimmer Papa Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 13 19:09:32 arbeitszimmer-papa volumio[1258]: info: Loading plugin "spop"... Dec 13 19:09:33 arbeitszimmer-papa sudo[1325]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:33 arbeitszimmer-papa dhcpcd[654]: timed out Dec 13 19:09:33 arbeitszimmer-papa sh[648]: timed out Dec 13 19:09:33 arbeitszimmer-papa sh[604]: ifup: failed to bring up eth0 Dec 13 19:09:33 arbeitszimmer-papa systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 13 19:09:33 arbeitszimmer-papa systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Loading plugin "outputs"... Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Loading plugin "albumart"... Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Plugin example_plugin is not enabled Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Loading plugin "inputs"... Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Loading plugin "updater_comm"... Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Plugin mpdemulation is not enabled Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Loading plugin "rest_api"... Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Loading plugin "websocket"... Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Starting Socket.io Server version 1.7.4 Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: Loading i18n strings for locale de Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: Updating browse sources language Dec 13 19:09:34 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::initPlayerControls Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: Express server listening on port 3000 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: [Metrics] WebUI: 9s 667.36ms Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::resetVolumioState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::getcurrentVolume Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Volumio Network Manager: Network status updated: 2 Dec 13 19:09:35 arbeitszimmer-papa volumio[1394]: Forking 3 albumart workers Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::pushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::updateTrackBlock Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrackBlock Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Reloading queue from file Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::pushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::setRepeat true single undefined Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::pushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::setRandom null Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::pushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Setting Device type: Raspberry PI Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Completed loading Core Plugins Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Preparing to generate the ALSA configuration file Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Discovery: adding d94d8fdb-be4d-48ec-a36a-5f2fda9c2a27 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Discovery: Found device Arbeitszimmer Papa Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Discovery: this is already registered, d94d8fdb-be4d-48ec-a36a-5f2fda9c2a27 Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: Discovery: Found device Arbeitszimmer Papa Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 13 19:09:35 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: Reading ALSA contributions from plugins. Dec 13 19:09:36 arbeitszimmer-papa systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: Output device has changed, restarting MPD Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: Output device has changed, restarting Shairport Sync Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:09:36 arbeitszimmer-papa sudo[1462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 19:09:36 arbeitszimmer-papa sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:36 arbeitszimmer-papa sudo[1462]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:36 arbeitszimmer-papa sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 13 19:09:36 arbeitszimmer-papa sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: ___________ START PLUGINS ___________ Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: ControllerMpd::onStart: Initializing MPD Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: Creating MPD Configuration file Dec 13 19:09:36 arbeitszimmer-papa systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 19:09:36 arbeitszimmer-papa systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: [1765649376754] CoreMusicLibrary::Adding element Medienserver Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 19:09:36 arbeitszimmer-papa sudo[1473]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 19:09:36 arbeitszimmer-papa sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 19:09:36 arbeitszimmer-papa sudo[1478]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 13 19:09:36 arbeitszimmer-papa sudo[1473]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:36 arbeitszimmer-papa sudo[1475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 19:09:36 arbeitszimmer-papa sudo[1475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:36 arbeitszimmer-papa sudo[1475]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:36 arbeitszimmer-papa sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 13 19:09:36 arbeitszimmer-papa sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:36 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:09:37 arbeitszimmer-papa systemd[1]: mpd.service: Deactivated successfully. Dec 13 19:09:37 arbeitszimmer-papa systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 13 19:09:37 arbeitszimmer-papa systemd[1]: mpd.socket: Deactivated successfully. Dec 13 19:09:37 arbeitszimmer-papa systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 13 19:09:37 arbeitszimmer-papa systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 13 19:09:37 arbeitszimmer-papa systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 19:09:37 arbeitszimmer-papa systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:09:37 arbeitszimmer-papa sudo[1487]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 19:09:37 arbeitszimmer-papa sudo[1487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 19:09:37 arbeitszimmer-papa sudo[1492]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 13 19:09:37 arbeitszimmer-papa sudo[1487]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:37 arbeitszimmer-papa nmbd[1059]: [2025/12/13 19:09:37.285161, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Dec 13 19:09:37 arbeitszimmer-papa nmbd[1059]: register_name_response: Answer name <00> differs from question name <00>. Dec 13 19:09:37 arbeitszimmer-papa nmbd[1059]: [2025/12/13 19:09:37.285544, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Dec 13 19:09:37 arbeitszimmer-papa nmbd[1059]: register_name_response: Answer name <03> differs from question name <03>. Dec 13 19:09:37 arbeitszimmer-papa nmbd[1059]: [2025/12/13 19:09:37.285758, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Dec 13 19:09:37 arbeitszimmer-papa nmbd[1059]: register_name_response: Answer name <20> differs from question name <20>. Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: [1765649377336] CoreMusicLibrary::Adding element Last_100 Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: [1765649377351] CoreMusicLibrary::Adding element Webradio Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: Initializing BBC Radios Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: Creating Spotify config file Dec 13 19:09:37 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:39 arbeitszimmer-papa volumio[1258]: info: Volumio Calling Home Dec 13 19:09:40 arbeitszimmer-papa volumio[1415]: Starting albumart workers Dec 13 19:09:41 arbeitszimmer-papa volumio[1416]: Starting albumart workers Dec 13 19:09:41 arbeitszimmer-papa sudo[1528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 13 19:09:41 arbeitszimmer-papa sudo[1528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:41 arbeitszimmer-papa sudo[1528]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:41 arbeitszimmer-papa sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 13 19:09:41 arbeitszimmer-papa sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:41 arbeitszimmer-papa sudo[1526]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:41 arbeitszimmer-papa volumio-remote-updater[682]: [2025-12-13 19:09:41] [connect] Successful connection Dec 13 19:09:42 arbeitszimmer-papa volumio[1258]: info: MPD Permissions set Dec 13 19:09:42 arbeitszimmer-papa volumio[1258]: info: MPD Permissions set Dec 13 19:09:42 arbeitszimmer-papa volumio[1417]: Starting albumart workers Dec 13 19:09:42 arbeitszimmer-papa volumio[1258]: info: Volumio called home Dec 13 19:09:42 arbeitszimmer-papa volumio[1258]: info: Spotify config file written Dec 13 19:09:42 arbeitszimmer-papa sudo[1537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 13 19:09:42 arbeitszimmer-papa sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:42 arbeitszimmer-papa mpd[1493]: 2025-12-13T19:09:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 19:09:42 arbeitszimmer-papa systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 13 19:09:42 arbeitszimmer-papa 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. Dec 13 19:09:42 arbeitszimmer-papa 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. Dec 13 19:09:42 arbeitszimmer-papa systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 13 19:09:42 arbeitszimmer-papa systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 13 19:09:42 arbeitszimmer-papa systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 13 19:09:42 arbeitszimmer-papa go-librespot[1541]: go-librespot daemon starting... Dec 13 19:09:42 arbeitszimmer-papa sudo[1537]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:42 arbeitszimmer-papa systemd[1]: Started mpd.service - Music Player Daemon. Dec 13 19:09:42 arbeitszimmer-papa sudo[1464]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:43 arbeitszimmer-papa sudo[1477]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: info: No need to fix Spotify hosts Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=info msg="running go-librespot 0.4.0" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="app state loaded" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=info msg="zeroconf server listening on port 39751" Dec 13 19:09:43 arbeitszimmer-papa volumio-remote-updater[682]: [2025-12-13 19:09:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765649381 101 Dec 13 19:09:43 arbeitszimmer-papa volumio[1258]: 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: 2 Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="obtained new client token: AADuAjwF+yCo8h/Jg4XufhJAWcMe9vKzbj6YehN8VKRo9c56b4du4DncDuWyROBdPCQoZxwm8k98uKmxWFWuG0qS82WyovuOuUXOFSZHD+4U5r6OIyRmtN0D6+m4U8/dhgkPhGy41voyxeM7j/+/thzgt2+UITiobYZYknwn4YVMK6tlQ+o6MeJal1msJle1Ywr5QFr1Ns7HuTQUSjuqVXyI4qZUNqufKnZnOoc8FWasyFtjS35eIRI=" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="completed keyexchange" Dec 13 19:09:43 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:43+01:00" level=debug msg="completed challenge" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=info msg="authenticated AP" username="ct*********************5s" Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: Completed starting Core Plugins Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: ------------------------------------------- Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: ----- MyVolumio plugins startup ---- Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: ------------------------------------------- Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=info msg="authenticated Login5" username="ct*********************5s" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="initializing zeroconf session" username="ct*********************5s" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="dealer connection opened" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=trace msg="starting accesspoint recv loop" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=trace msg="starting dealer recv loop" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=trace msg="received accesspoint ping" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="received connection id: Nzk3NTdjOGYtMzUy...Q0ZENDczOTU4RQ==" Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=trace msg="received accesspoint pong ack" Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: SPOTIFY: BQBcxPyPyIQDHzTyKxVi0dMPB6Pb4-r_YxqdmXmrfxMDnV4HVwuoeoGSd96QuM8Ep2ftAvjdrSqt-QRPIr0FSMewmbD0vFGdRQoAjamvBEXa3fWsmKRFZXMG700MnO7B11dhCogpmg_rgnvSGgA3Vnc8MuywQ4IczVWiOORDbWvbdNEn8HaM0FcANi_xQIQ5QGp_1XraDvp0cWi0mvLh9LeAZ8MmBoeKzyRUQzDYMpzT7c8qDShdOgEwHW667eGX3S2hs0LsJzws_NEmj8Nx69DJR8EbGpW1YldQ6AjsZcnfIOiVNEPytp-L Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: New Spotify access token = BQBcxPyPyIQDHzTyKxVi0dMPB6Pb4-r_YxqdmXmrfxMDnV4HVwuoeoGSd96QuM8Ep2ftAvjdrSqt-QRPIr0FSMewmbD0vFGdRQoAjamvBEXa3fWsmKRFZXMG700MnO7B11dhCogpmg_rgnvSGgA3Vnc8MuywQ4IczVWiOORDbWvbdNEn8HaM0FcANi_xQIQ5QGp_1XraDvp0cWi0mvLh9LeAZ8MmBoeKzyRUQzDYMpzT7c8qDShdOgEwHW667eGX3S2hs0LsJzws_NEmj8Nx69DJR8EbGpW1YldQ6AjsZcnfIOiVNEPytp-L Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 13 19:09:44 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:44+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: assert.ok(self.idling) Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: error: The expression evaluated to a falsy value: Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: assert.ok(self.idling) Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: MPD running with PID1493 Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: ,establishing connection Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: Starting Shairport Sync Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: Starting Shairport Sync Dec 13 19:09:44 arbeitszimmer-papa volumio[1258]: info: Starting Shairport Sync Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: error: updateQueue error: null Dec 13 19:09:45 arbeitszimmer-papa sudo[1584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 19:09:45 arbeitszimmer-papa sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:45 arbeitszimmer-papa sudo[1586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 19:09:45 arbeitszimmer-papa sudo[1586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: error: updateQueue error: null Dec 13 19:09:45 arbeitszimmer-papa sudo[1588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 19:09:45 arbeitszimmer-papa sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:45 arbeitszimmer-papa sudo[1590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 13 19:09:45 arbeitszimmer-papa sudo[1590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:45 arbeitszimmer-papa systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 13 19:09:45 arbeitszimmer-papa systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 13 19:09:45 arbeitszimmer-papa systemd[1]: shairport-sync.service: Deactivated successfully. Dec 13 19:09:45 arbeitszimmer-papa systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 19:09:45 arbeitszimmer-papa systemd[1]: shairport-sync.service: Consumed 1.353s CPU time. Dec 13 19:09:45 arbeitszimmer-papa systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 19:09:45 arbeitszimmer-papa sudo[1584]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:45 arbeitszimmer-papa systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 13 19:09:45 arbeitszimmer-papa sudo[1590]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:45 arbeitszimmer-papa sudo[1588]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:45 arbeitszimmer-papa sudo[1586]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: Shairport-Sync Started Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: Error adding Membership: Error: addMembership EINVAL Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: Upmpdcli Daemon Started Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: Shairport-Sync Started Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: Shairport-Sync Started Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioGetState Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: SPOTIFY: User informations: {"country":"DE","display_name":"Lotenamn","email":"kroeder@web.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/ct3jwvn67g34xyjolou0jn45s"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/ct3jwvn67g34xyjolou0jn45s","id":"ct3jwvn67g34xyjolou0jn45s","images":[],"product":"premium","type":"user","uri":"spotify:user:ct3jwvn67g34xyjolou0jn45s"} Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: Spotify Successfully logged in Dec 13 19:09:45 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 19:09:46 arbeitszimmer-papa volumio[1258]: info: [1765649386048] CoreMusicLibrary::Adding element Spotify Dec 13 19:09:46 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 19:09:46 arbeitszimmer-papa volumio[1258]: Cannot find translation for source Spotify Dec 13 19:09:47 arbeitszimmer-papa volumio[1258]: info: go-librespot daemon successfully initialized Dec 13 19:09:47 arbeitszimmer-papa sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 19:09:47 arbeitszimmer-papa sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:47 arbeitszimmer-papa sudo[1651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 19:09:47 arbeitszimmer-papa sudo[1651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:09:47 arbeitszimmer-papa systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 13 19:09:47 arbeitszimmer-papa systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 13 19:09:47 arbeitszimmer-papa systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 19:09:47 arbeitszimmer-papa mpd_monitor.sh[1658]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 19:09:47 arbeitszimmer-papa sudo[1649]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:47 arbeitszimmer-papa sudo[1651]: pam_unix(sudo:session): session closed for user root Dec 13 19:09:47 arbeitszimmer-papa volumio[1258]: info: Successfully started MPD Monitor Dec 13 19:09:47 arbeitszimmer-papa volumio[1258]: info: Successfully started MPD Monitor Dec 13 19:09:50 arbeitszimmer-papa volumio[1258]: info: Initializing connection to go-librespot Websocket Dec 13 19:09:50 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:09:50+01:00" level=debug msg="new websocket client" Dec 13 19:09:50 arbeitszimmer-papa volumio[1258]: info: Connection to go-librespot Websocket established Dec 13 19:09:50 arbeitszimmer-papa volumio[1258]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 13 19:09:55 arbeitszimmer-papa upmpdcli[1676]: writing RSA key Dec 13 19:09:56 arbeitszimmer-papa volumio[1258]: info: Getting Spotify volume Dec 13 19:10:02 arbeitszimmer-papa volumio[1258]: info: Spotify volume: 100 Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: error: MyVolumio Plugin failed to start in a timely fashion Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: [Metrics] CommandRouter: 40s 172.42ms Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumiosetStartupVolume Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: VolumeController:: Setting startup Volume 100 Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: VolumeController::SetAlsaVolume100 Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::Close All Modals sent Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::Close All Modals sent Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreStateMachine::pushState Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CorePlayQueue::getTrack 0 Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 19:10:06 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::volumioPushState Dec 13 19:10:08 arbeitszimmer-papa volumio[1258]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 13 19:10:08 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 13 19:10:08 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 19:10:08 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 13 19:10:08 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 13 19:10:10 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 19:10:10 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:10:10 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 13 19:10:14 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:10:14+01:00" level=trace msg="sent dealer ping" Dec 13 19:10:14 arbeitszimmer-papa go-librespot[1542]: time="2025-12-13T19:10:14+01:00" level=trace msg="received dealer pong" Dec 13 19:10:14 arbeitszimmer-papa volumio[1258]: info: BOOT COMPLETED Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 13 19:10:16 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: Adding plugin bluetooth to MyMusic Plugins Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: Adding plugin multiroom to MyMusic Plugins Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: Adding plugin metavolumio to MyMusic Plugins Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: Adding plugin cd_controller to MyMusic Plugins Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 13 19:10:17 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: Starting MyVolumio Remote Streaming Endpoints Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: MyVolumio login type: Token Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 13 19:10:20 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 13 19:10:24 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 13 19:10:24 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 13 19:10:24 arbeitszimmer-papa volumio[1258]: info: Streaming services startup Dec 13 19:10:24 arbeitszimmer-papa volumio[1258]: info: Starting Streaming Daemon Dec 13 19:10:24 arbeitszimmer-papa volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 13 19:10:24 arbeitszimmer-papa sudo[1744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 13 19:10:24 arbeitszimmer-papa sudo[1744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:10:24 arbeitszimmer-papa sudo[1744]: pam_unix(sudo:session): session closed for user root Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: at new NodeError (node:internal/errors:405:5) Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: at assert (node:internal/assert:14:11) Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: at internalConnectMultiple (node:net:1118:3) Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: at listOnTimeout (node:internal/timers:575:11) Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: at process.processTimers (node:internal/timers:514:7) { Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: code: 'ERR_INTERNAL_ASSERTION' Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: } Dec 13 19:10:25 arbeitszimmer-papa volumio[1258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 19:10:28 arbeitszimmer-papa sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 19:09' Dec 13 19:10:28 arbeitszimmer-papa sudo[1770]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="b5a4a000e056eaff366fbb6591bf2240b12246d0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Nov 8 06:10:52 UTC 2025" VOLUMIO_VERSION="4.067" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="78c9815e90cf3bee23e5cdc5934a01e9"