-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-12-07 19:23:51 CET. -- Dec 07 19:23:29 volumiolego systemd-timedated[1101]: Changed local time to Sun Dec 7 19:23:29 2025 Dec 07 19:23:29 volumiolego sudo[1099]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:29 volumiolego volumio-time-update[697]: volumio-time-update-util: System time updated successfully. Dec 07 19:23:29 volumiolego systemd[1]: Starting Daily apt download activities... Dec 07 19:23:29 volumiolego systemd[1]: Started Volumio Time Update Utility. Dec 07 19:23:30 volumiolego nmbd[837]: [2025/12/07 19:23:30.092198, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 07 19:23:30 volumiolego nmbd[837]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 07 19:23:30 volumiolego systemd[1]: Started Samba NMB Daemon. Dec 07 19:23:30 volumiolego systemd[1]: Starting Samba Winbind Daemon... Dec 07 19:23:30 volumiolego nmbd[837]: [2025/12/07 19:23:30.219488, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 07 19:23:30 volumiolego nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.28 for name WORKGROUP<1d>. Dec 07 19:23:30 volumiolego nmbd[837]: This response was from IP 192.168.0.32, reporting an IP address of 192.168.0.32. Dec 07 19:23:30 volumiolego volumio[1069]: info: MYVOLUMIO Environment detected Dec 07 19:23:30 volumiolego winbindd[1122]: [2025/12/07 19:23:30.720371, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 07 19:23:30 volumiolego winbindd[1122]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 07 19:23:30 volumiolego winbindd[1122]: [2025/12/07 19:23:30.861814, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 07 19:23:30 volumiolego winbindd[1122]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 07 19:23:30 volumiolego systemd[1]: Started Samba Winbind Daemon. Dec 07 19:23:30 volumiolego systemd[1]: Starting Samba SMB Daemon... Dec 07 19:23:31 volumiolego volumio[1069]: info: Plugin folders cleanup Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning into folder /volumio/app/plugins/ Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category audio_interface Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category miscellanea Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category music_service Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category plugins.json Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category system_controller Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category user_interface Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning into folder /data/plugins/ Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category music_service Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category system_hardware Dec 07 19:23:31 volumiolego volumio[1069]: info: Scanning category user_interface Dec 07 19:23:31 volumiolego volumio[1069]: info: Plugin folders cleanup completed Dec 07 19:23:31 volumiolego volumio[1069]: info: ------------------------------------------- Dec 07 19:23:31 volumiolego volumio[1069]: info: ----- Core plugins startup ---- Dec 07 19:23:31 volumiolego volumio[1069]: info: ------------------------------------------- Dec 07 19:23:31 volumiolego volumio[1069]: info: Loading plugins from folder /volumio/app/plugins/ Dec 07 19:23:31 volumiolego volumio[1069]: info: Adding plugin upnp to MyMusic Plugins Dec 07 19:23:31 volumiolego volumio[1069]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 07 19:23:31 volumiolego volumio[1069]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 07 19:23:31 volumiolego volumio[1069]: info: Loading plugins from folder /data/plugins/ Dec 07 19:23:31 volumiolego volumio[1069]: info: Loading plugin "system"... Dec 07 19:23:31 volumiolego volumio[1069]: info: Loading plugin "appearance"... Dec 07 19:23:31 volumiolego smbd[1129]: [2025/12/07 19:23:31.804899, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 07 19:23:31 volumiolego smbd[1129]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 07 19:23:31 volumiolego systemd[1]: Started Samba SMB Daemon. Dec 07 19:23:31 volumiolego systemd[1]: Reached target Multi-User System. Dec 07 19:23:31 volumiolego systemd[1]: Reached target Graphical Interface. Dec 07 19:23:31 volumiolego systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 07 19:23:31 volumiolego systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 07 19:23:31 volumiolego systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 07 19:23:32 volumiolego systemd[1]: apt-daily.service: Succeeded. Dec 07 19:23:32 volumiolego systemd[1]: Started Daily apt download activities. Dec 07 19:23:32 volumiolego systemd[1]: Startup finished in 13.902s (kernel) + 25.758s (userspace) = 39.661s. Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "network"... Dec 07 19:23:33 volumiolego volumio[1069]: info: Refreshing Cached IP Addresses Dec 07 19:23:33 volumiolego sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 19:23:33 volumiolego sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:33 volumiolego sudo[1171]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:33 volumiolego sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 19:23:33 volumiolego sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:33 volumiolego sudo[1173]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "services"... Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "alsa_controller"... Dec 07 19:23:33 volumiolego sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 07 19:23:33 volumiolego sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:33 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "wizard"... Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "networkfs"... Dec 07 19:23:33 volumiolego volumio[1069]: info: Starting Udev Watcher for removable devices Dec 07 19:23:33 volumiolego sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tom,password=Tkl60318!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.69/Multimedia/Music/Kinder /mnt/NAS/Kinder Dec 07 19:23:33 volumiolego sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:33 volumiolego sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tom,password=Tkl60318!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.69/multimedia/Music/Classic /mnt/NAS/Classic Dec 07 19:23:33 volumiolego sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:33 volumiolego volumio[1069]: info: Ignoring mount for partition: boot Dec 07 19:23:33 volumiolego volumio[1069]: info: Ignoring mount for partition: volumio Dec 07 19:23:33 volumiolego volumio[1069]: info: Ignoring mount for partition: volumio_data Dec 07 19:23:33 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "volumio_command_line_client"... Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "upnp"... Dec 07 19:23:33 volumiolego volumio[1069]: info: [1765131813648] Starting Upmpd Daemon Dec 07 19:23:33 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "my_music"... Dec 07 19:23:33 volumiolego volumio[1069]: info: Loading plugin "mpd"... Dec 07 19:23:33 volumiolego kernel: Key type cifs.spnego registered Dec 07 19:23:33 volumiolego kernel: Key type cifs.idmap registered Dec 07 19:23:33 volumiolego 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 07 19:23:33 volumiolego kernel: CIFS: Attempting to mount //192.168.0.69/Multimedia/Music/Kinder Dec 07 19:23:34 volumiolego kernel: cryptd: max_cpu_qlen set to 1000 Dec 07 19:23:34 volumiolego sudo[1182]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:34 volumiolego volumio[1069]: info: Loading plugin "upnp_browser"... Dec 07 19:23:34 volumiolego sudo[1199]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:34 volumiolego kernel: CIFS: Attempting to mount //192.168.0.69/multimedia/Music/Classic Dec 07 19:23:34 volumiolego sudo[1201]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:34 volumiolego volumio[1069]: info: Loading plugin "alarm-clock"... Dec 07 19:23:35 volumiolego volumio[1069]: info: Loading plugin "airplay_emulation"... Dec 07 19:23:35 volumiolego volumio[1069]: info: Starting Shairport Sync Dec 07 19:23:35 volumiolego volumio[1069]: info: Loading plugin "last_100"... Dec 07 19:23:35 volumiolego volumio[1069]: info: Loading plugin "webradio"... Dec 07 19:23:35 volumiolego volumio[1069]: info: Loading plugin "i2s_dacs"... Dec 07 19:23:35 volumiolego volumio[1069]: info: Loading plugin "volumiodiscovery"... Dec 07 19:23:35 volumiolego volumio[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 19:23:35 volumiolego volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 19:23:35 volumiolego volumio[1069]: *** WARNING *** For more information see Dec 07 19:23:35 volumiolego volumio[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 19:23:35 volumiolego volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 19:23:35 volumiolego volumio[1069]: *** WARNING *** For more information see Dec 07 19:23:35 volumiolego node[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 19:23:35 volumiolego node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 19:23:35 volumiolego node[1069]: *** WARNING *** For more information see Dec 07 19:23:35 volumiolego node[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 19:23:35 volumiolego node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 19:23:35 volumiolego node[1069]: *** WARNING *** For more information see Dec 07 19:23:35 volumiolego volumio[1069]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 07 19:23:35 volumiolego volumio[1069]: info: Discovery: Started advertising with name: VolumioLEGO Dec 07 19:23:35 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 19:23:35 volumiolego volumio[1069]: info: Loading plugin "spop"... Dec 07 19:23:35 volumiolego volumio-remote-updater[690]: [2025-12-07 19:23:35] [connect] Successful connection Dec 07 19:23:37 volumiolego volumio[1069]: info: Loading plugin "outputs"... Dec 07 19:23:37 volumiolego volumio[1069]: info: Loading plugin "albumart"... Dec 07 19:23:37 volumiolego volumio[1069]: info: Plugin example_plugin is not enabled Dec 07 19:23:37 volumiolego volumio[1069]: info: Loading plugin "inputs"... Dec 07 19:23:37 volumiolego volumio[1069]: info: Loading plugin "updater_comm"... Dec 07 19:23:37 volumiolego volumio[1069]: info: Plugin mpdemulation is not enabled Dec 07 19:23:37 volumiolego volumio[1069]: info: Loading plugin "rest_api"... Dec 07 19:23:37 volumiolego volumio[1069]: info: Loading plugin "websocket"... Dec 07 19:23:37 volumiolego volumio[1069]: info: Starting Socket.io Server version 2.3.0 Dec 07 19:23:37 volumiolego volumio[1069]: info: Loading plugin "rotaryencoder2"... Dec 07 19:23:37 volumiolego volumio[1069]: Forking 3 albumart workers Dec 07 19:23:38 volumiolego volumio[1069]: info: Loading plugin "Systeminfo"... Dec 07 19:23:38 volumiolego volumio[1069]: Starting albumart workers Dec 07 19:23:38 volumiolego volumio[1069]: Starting albumart workers Dec 07 19:23:38 volumiolego volumio[1069]: Starting albumart workers Dec 07 19:23:38 volumiolego volumio[1069]: info: Loading plugin "touch_display"... Dec 07 19:23:39 volumiolego volumio[1069]: info: Applying required configuration parameters for plugin touch_display Dec 07 19:23:39 volumiolego volumio[1069]: info: Loading i18n strings for locale de Dec 07 19:23:39 volumiolego volumio[1069]: Updating browse sources language Dec 07 19:23:39 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::initPlayerControls Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: Express server listening on port 3000 Dec 07 19:23:40 volumiolego volumio[1069]: [Metrics] WebUI: 12s 232.77ms Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::resetVolumioState Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::getcurrentVolume Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 19:23:40 volumiolego volumio[1069]: info: Volumio Network Manager: Network status updated: 2 Dec 07 19:23:40 volumiolego volumio-remote-updater[690]: [2025-12-07 19:23:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765131815 101 Dec 07 19:23:40 volumiolego volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Dec 07 19:23:40 volumiolego volumio[1069]: verbose: New Socket.io Connection to 192.168.0.28:3000 from 192.168.0.41 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 07 19:23:40 volumiolego volumio[1069]: 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 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: Reloading queue from file Dec 07 19:23:40 volumiolego volumio[1069]: info: Setting Device type: Raspberry PI Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::setRepeat false single undefined Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::pushState Dec 07 19:23:40 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioPushState Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::setRandom null Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::pushState Dec 07 19:23:40 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioPushState Dec 07 19:23:40 volumiolego volumio[1069]: info: Completed loading Core Plugins Dec 07 19:23:40 volumiolego volumio[1069]: info: Preparing to generate the ALSA configuration file Dec 07 19:23:40 volumiolego volumio[1069]: info: VolumeController:: Volume=11 Mute =false Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::pushState Dec 07 19:23:40 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioPushState Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreStateMachine::updateTrackBlock Dec 07 19:23:40 volumiolego volumio[1069]: info: CorePlayQueue::getTrackBlock Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 19:23:40 volumiolego volumio[1069]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 07 19:23:40 volumiolego volumio[1069]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Dec 07 19:23:40 volumiolego volumio[1069]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 07 19:23:40 volumiolego systemd[1]: systemd-fsckd.service: Succeeded. Dec 07 19:23:40 volumiolego volumio[1069]: info: Asound.conf file unchanged, so no further update is needed Dec 07 19:23:40 volumiolego volumio[1069]: info: Output device has changed, restarting MPD Dec 07 19:23:40 volumiolego volumio[1069]: info: Output device has changed, restarting Shairport Sync Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:23:40 volumiolego sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 19:23:40 volumiolego sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:40 volumiolego sudo[1314]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:40 volumiolego sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 19:23:40 volumiolego sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:40 volumiolego systemd[1]: Stopping Music Player Daemon... Dec 07 19:23:40 volumiolego volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 19:23:40 volumiolego volumio[1069]: info: ___________ START PLUGINS ___________ Dec 07 19:23:40 volumiolego volumio[1069]: info: ControllerMpd::onStart: Initializing MPD Dec 07 19:23:40 volumiolego volumio[1069]: info: Creating MPD Configuration file Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 19:23:40 volumiolego volumio[1069]: info: [1765131820829] CoreMusicLibrary::Adding element Medienserver Dec 07 19:23:40 volumiolego sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 19:23:40 volumiolego sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:40 volumiolego sudo[1323]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:40 volumiolego sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 19:23:40 volumiolego sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:23:40 volumiolego systemd[1]: mpd.service: Succeeded. Dec 07 19:23:40 volumiolego systemd[1]: Stopped Music Player Daemon. Dec 07 19:23:40 volumiolego volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 19:23:40 volumiolego volumio[1069]: info: [1765131820975] CoreMusicLibrary::Adding element Last_100 Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 19:23:40 volumiolego volumio[1069]: info: [1765131820979] CoreMusicLibrary::Adding element Webradio Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 19:23:40 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 19:23:40 volumiolego volumio[1069]: info: Initializing BBC Radios Dec 07 19:23:40 volumiolego systemd[1]: Starting Music Player Daemon... Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: Creating Spotify config file Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: Loading i18n strings for locale de Dec 07 19:23:41 volumiolego sudo[1332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 07 19:23:41 volumiolego sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 19:23:41 volumiolego volumio[1069]: info: Volumio Calling Home Dec 07 19:23:41 volumiolego sudo[1332]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:41 volumiolego sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=27 relative_axis=true steps-per-period=1 Dec 07 19:23:41 volumiolego sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:41 volumiolego sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 07 19:23:41 volumiolego sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:41 volumiolego sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 07 19:23:41 volumiolego sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:41 volumiolego sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 07 19:23:41 volumiolego sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:41 volumiolego kernel: rotary-encoder rotary@16: gray Dec 07 19:23:41 volumiolego kernel: input: rotary@16 as /devices/platform/rotary@16/input/input3 Dec 07 19:23:41 volumiolego sudo[1354]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:41 volumiolego sudo[1351]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:41 volumiolego systemd[1]: Reloading. Dec 07 19:23:41 volumiolego volumio[1069]: info: Discovery: adding 0a832379-d93e-4b9b-9939-a922071c46b3 Dec 07 19:23:41 volumiolego volumio[1069]: info: Discovery: Found device VolumioLEGO Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:41 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:41 volumiolego volumio[1069]: info: VolumeController:: Volume=11 Mute =false Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreStateMachine::pushState Dec 07 19:23:41 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::volumioPushState Dec 07 19:23:41 volumiolego volumio[1069]: info: Discovery: adding 90de26a0-0915-46ea-9c61-7ad18a7ab556 Dec 07 19:23:41 volumiolego volumio[1069]: info: Discovery: Found device VolumioDach Dec 07 19:23:41 volumiolego volumio[1069]: info: Discovery: Connecting to remote: 192.168.0.41 Dec 07 19:23:41 volumiolego volumio[1069]: info: touch_display: Backlight interface detected. Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:41 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:41 volumiolego systemd-udevd[1366]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 07 19:23:41 volumiolego volumio[1069]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 07 19:23:41 volumiolego volumio[1069]: info: MPD Permissions set Dec 07 19:23:41 volumiolego volumio[1069]: info: MPD Permissions set Dec 07 19:23:41 volumiolego volumio[1069]: info: Volumio called home Dec 07 19:23:41 volumiolego volumio[1069]: info: Spotify config file written Dec 07 19:23:41 volumiolego sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 07 19:23:41 volumiolego sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:41 volumiolego volumio[1069]: info: Discovery: Connected to remote: 192.168.0.41 Dec 07 19:23:41 volumiolego volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 07 19:23:41 volumiolego volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 19:23:41 volumiolego volumio[1069]: info: No need to fix Spotify hosts Dec 07 19:23:42 volumiolego systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 07 19:23:42 volumiolego volumio[1069]: info: touch_display: Raspberry Pi Foundation touch screen detected. Dec 07 19:23:42 volumiolego sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 07 19:23:42 volumiolego sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:42 volumiolego sudo[1401]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:42 volumiolego sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 07 19:23:42 volumiolego sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:42 volumiolego sudo[1403]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:42 volumiolego volumio[1069]: info: Starting Shairport Sync Dec 07 19:23:42 volumiolego sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Dec 07 19:23:42 volumiolego sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:42 volumiolego sudo[1406]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:42 volumiolego volumio[1069]: info: Starting Shairport Sync Dec 07 19:23:42 volumiolego volumio[1069]: info: Starting Shairport Sync Dec 07 19:23:42 volumiolego sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 19:23:42 volumiolego sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:42 volumiolego sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 19:23:42 volumiolego sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:42 volumiolego sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 19:23:42 volumiolego sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:42 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:42 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:42 volumiolego sudo[1356]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:42 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:42 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:42 volumiolego systemd[1]: Reloading. Dec 07 19:23:42 volumiolego volumio[1069]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 07 19:23:42 volumiolego volumio[1069]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 07 19:23:42 volumiolego volumio[1069]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 07 19:23:42 volumiolego volumio[1069]: info: touch_display: File permissions for backlight brightness control set. Dec 07 19:23:42 volumiolego sh[608]: timed out Dec 07 19:23:42 volumiolego dhcpcd[644]: timed out Dec 07 19:23:42 volumiolego sh[608]: dhcpcd exited Dec 07 19:23:42 volumiolego dhcpcd[644]: dhcpcd exited Dec 07 19:23:42 volumiolego sh[608]: ifup: failed to bring up eth0 Dec 07 19:23:42 volumiolego sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=5 active_low=1 gpio_pull=up keycode=20 Dec 07 19:23:42 volumiolego sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:42 volumiolego volumio[1069]: verbose: New Socket.io Connection to 192.168.0.28:3000 from 192.168.0.22 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 07 19:23:42 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 19:23:42 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 19:23:42 volumiolego volumio[1069]: info: Discovery: Getting this device information Dec 07 19:23:42 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:42 volumiolego kernel: input: button@5 as /devices/platform/button@5/input/input4 Dec 07 19:23:42 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:42 volumiolego volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 19:23:42 volumiolego sudo[1433]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 07 19:23:43 volumiolego systemd-udevd[1366]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 07 19:23:43 volumiolego mpd[1347]: Dec 07 19:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 07 19:23:43 volumiolego systemd[1]: Started Music Player Daemon. Dec 07 19:23:43 volumiolego sudo[1316]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 07 19:23:43 volumiolego sudo[1359]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 07 19:23:43 volumiolego sudo[1325]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego volumio[1069]: info: touch_display: systemctl daemon-reload succeeded. Dec 07 19:23:43 volumiolego sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 07 19:23:43 volumiolego sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:43 volumiolego systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 07 19:23:43 volumiolego systemd[1]: Started go-librespot Daemon. Dec 07 19:23:43 volumiolego go-librespot[1467]: go-librespot daemon starting... Dec 07 19:23:43 volumiolego systemd[1]: shairport-sync.service: Succeeded. Dec 07 19:23:43 volumiolego systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 07 19:23:43 volumiolego sudo[1390]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 19:23:43 volumiolego sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:43 volumiolego systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 07 19:23:43 volumiolego sudo[1410]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego sudo[1470]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 19:23:43 volumiolego sudo[1412]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego sudo[1415]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:43 volumiolego sudo[1472]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego systemd[1]: Started Volumio Kiosk. Dec 07 19:23:43 volumiolego sudo[1463]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:43 volumiolego volumio[1069]: error: MPD error: The expression evaluated to a falsy value: Dec 07 19:23:43 volumiolego volumio[1069]: assert.ok(self.idling) Dec 07 19:23:43 volumiolego volumio[1069]: error: The expression evaluated to a falsy value: Dec 07 19:23:43 volumiolego volumio[1069]: assert.ok(self.idling) Dec 07 19:23:43 volumiolego volumio[1069]: info: MPD running with PID1347 Dec 07 19:23:43 volumiolego volumio[1069]: ,establishing connection Dec 07 19:23:43 volumiolego volumio[1069]: error: MPD error: The expression evaluated to a falsy value: Dec 07 19:23:43 volumiolego volumio[1069]: assert.ok(self.idling) Dec 07 19:23:43 volumiolego volumio[1069]: error: The expression evaluated to a falsy value: Dec 07 19:23:43 volumiolego volumio[1069]: assert.ok(self.idling) Dec 07 19:23:43 volumiolego volumio[1069]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 07 19:23:43 volumiolego volumio[1069]: info: touch_display: Volumio Kiosk started. Dec 07 19:23:43 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:43 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:43 volumiolego volumio[1069]: info: Shairport-Sync Started Dec 07 19:23:43 volumiolego volumio[1069]: Error adding Membership: Error: addMembership EINVAL Dec 07 19:23:43 volumiolego volumio[1069]: info: Shairport-Sync Started Dec 07 19:23:43 volumiolego volumio[1069]: info: Shairport-Sync Started Dec 07 19:23:43 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:43 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:43 volumiolego volumio[1069]: error: updateQueue error: null Dec 07 19:23:43 volumiolego volumio[1069]: info: touch_display: X display number found: Dec 07 19:23:43 volumiolego sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 07 19:23:43 volumiolego sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:44 volumiolego sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 07 19:23:44 volumiolego sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:44 volumiolego sudo[1512]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:44 volumiolego sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 07 19:23:44 volumiolego systemd[1]: Started UPnP Renderer front-end to MPD. Dec 07 19:23:44 volumiolego sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:44 volumiolego sudo[1509]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:44 volumiolego sudo[1517]: pam_unix(sudo:session): session closed for user root Dec 07 19:23:44 volumiolego volumio[1069]: info: Completed starting Core Plugins Dec 07 19:23:44 volumiolego volumio[1069]: info: ------------------------------------------- Dec 07 19:23:44 volumiolego volumio[1069]: info: ----- MyVolumio plugins startup ---- Dec 07 19:23:44 volumiolego volumio[1069]: info: ------------------------------------------- Dec 07 19:23:44 volumiolego volumio[1069]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 07 19:23:44 volumiolego startx[1476]: X.Org X Server 1.20.4 Dec 07 19:23:44 volumiolego startx[1476]: X Protocol Version 11, Revision 0 Dec 07 19:23:44 volumiolego startx[1476]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Dec 07 19:23:44 volumiolego startx[1476]: Current Operating System: Linux volumiolego 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Dec 07 19:23:44 volumiolego startx[1476]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 07 19:23:44 volumiolego startx[1476]: Build Date: 04 April 2023 07:50:56AM Dec 07 19:23:44 volumiolego startx[1476]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Dec 07 19:23:44 volumiolego startx[1476]: Current version of pixman: 0.36.0 Dec 07 19:23:44 volumiolego startx[1476]: Before reporting problems, check http://wiki.x.org Dec 07 19:23:44 volumiolego startx[1476]: to make sure that you have the latest version. Dec 07 19:23:44 volumiolego startx[1476]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 07 19:23:44 volumiolego startx[1476]: (++) from command line, (!!) notice, (II) informational, Dec 07 19:23:44 volumiolego startx[1476]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 07 19:23:44 volumiolego startx[1476]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Dec 7 19:23:44 2025 Dec 07 19:23:44 volumiolego volumio[1069]: info: touch_display: X display number found: 0 Dec 07 19:23:44 volumiolego volumio[1069]: info: Upmpdcli Daemon Started Dec 07 19:23:44 volumiolego startx[1476]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 07 19:23:44 volumiolego startx[1476]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 07 19:23:44 volumiolego volumio[1069]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 07 19:23:44 volumiolego volumio[1069]: info: touch_display: X display number found: 0 Dec 07 19:23:44 volumiolego volumio[1514]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 07 19:23:44 volumiolego go-librespot[1467]: time="2025-12-07T19:23:44+01:00" level=info msg="running go-librespot 0.4.0" Dec 07 19:23:44 volumiolego go-librespot[1467]: time="2025-12-07T19:23:44+01:00" level=debug msg="app state loaded" Dec 07 19:23:44 volumiolego go-librespot[1467]: time="2025-12-07T19:23:44+01:00" level=debug msg="stored credentials not found" Dec 07 19:23:44 volumiolego go-librespot[1467]: time="2025-12-07T19:23:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 07 19:23:44 volumiolego systemd[1]: systemd-hostnamed.service: Succeeded. Dec 07 19:23:45 volumiolego go-librespot[1467]: time="2025-12-07T19:23:45+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-gew1.spotify.com:80]" Dec 07 19:23:45 volumiolego go-librespot[1467]: time="2025-12-07T19:23:45+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 07 19:23:45 volumiolego go-librespot[1467]: time="2025-12-07T19:23:45+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 07 19:23:45 volumiolego go-librespot[1467]: time="2025-12-07T19:23:45+01:00" level=info msg="zeroconf server listening on port 42373" Dec 07 19:23:46 volumiolego kernel: broken atomic modeset userspace detected, disabling atomic Dec 07 19:23:46 volumiolego startx[1476]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Dec 07 19:23:46 volumiolego volumio[1069]: info: go-librespot daemon successfully initialized Dec 07 19:23:47 volumiolego volumio[1069]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 07 19:23:47 volumiolego volumio[1514]: ...................++++ Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="obtained new client token: AACDVj5fVmfiRIMvcgW0aaVe20RHQClFZ0hBLr9jJD0V3ljAHfdKp1+XolLF/AiQV/GyU6ZdfHxlom1Z0Bvpf1wKiGqPGHn8evSOE9iCrZc9V6WEjMkvPDxrB+3ZsxLcaLjAf+9uj0kFEAcqGkxXETxPlb8ffwJhmXpVPIkM3SEr525PhY8x8mgrFQPWpiSkyphvd7AbmH4e8kBNhxRmxg//Uyrh1319+rTG096oH0+E7hKFgzEF3dkB" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="completed keyexchange" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="completed challenge" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=info msg="authenticated AP" username="31************************km" Dec 07 19:23:48 volumiolego volumio[1069]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=info msg="authenticated Login5" username="31************************km" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=info msg="accepted zeroconf from Pixel 8 Pro" username="31************************km" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="dealer connection opened" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=trace msg="starting accesspoint recv loop" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=trace msg="starting dealer recv loop" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=trace msg="received accesspoint ping" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 07 19:23:48 volumiolego go-librespot[1467]: time="2025-12-07T19:23:48+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="received connection id: NWJiMjU5NDctY2I3...MkRENkNBQTIwMA==" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=trace msg="received accesspoint pong ack" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="handling transfer player command from bee8610f483eb25f1029e30f2afa00b2e7056dcc" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="resolved context of episode" uri="spotify:show:6LuAZxNcc4sjMoJRhfZI4L" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=trace msg="fetched new page 0 with 32 items (list: 32)" uri="spotify:show:6LuAZxNcc4sjMoJRhfZI4L" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="loading episode (paused: false, position: 105553ms)" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=trace msg="emitting websocket event: will_play" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 391" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="selected format OGG_VORBIS_96 (0ea572ed81814d49d8c6e266e0746733dc5142e0)" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="requested aes key for file 0ea572ed81814d49d8c6e266e0746733dc5142e0, gid: 6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2030" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=trace msg="found 2 cdn urls" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1461" Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" Dec 07 19:23:49 volumiolego volumio[1069]: info: Initializing connection to go-librespot Websocket Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="new websocket client" Dec 07 19:23:49 volumiolego volumio[1069]: info: Connection to go-librespot Websocket established Dec 07 19:23:49 volumiolego go-librespot[1467]: time="2025-12-07T19:23:49+01:00" level=debug msg="fetched first chunk of 32, total size is 16664736 bytes" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="fetched chunk 1/31, size: 524288" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="fetched chunk 4/31, size: 524288" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="fetched chunk 2/31, size: 524288" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=trace msg="seek to 105553ms (diff: 148ms, samples: 4654887, bytes: 1179534)" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="created new output device" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=info msg="loaded episode \"\\\"Was ist Geduld?\\\" MiRA SHOW\" (paused: false, position: 105553ms, duration: 1533311ms, prefetched: false)" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="fetched chunk 5/31, size: 524288" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="fetched chunk 3/31, size: 524288" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=trace msg="scheduling prefetch in 1398s" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=trace msg="emitting websocket event: metadata" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=trace msg="emitting websocket event: active" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="sending successful reply for dealer request" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:6uLqjwWlYcBQkfQixyDgy8/resume-point-revision/80a35721-e416-4a0a-9909-2447ead7b7c4" Dec 07 19:23:50 volumiolego volumio[1069]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:episode:6uLqjwWlYcBQkfQixyDgy8","name":"\"Was ist Geduld?\" MiRA SHOW","artist_names":["MiRA SHOW - der Kinderpodcast"],"album_name":"MiRA SHOW - der Kinderpodcast","album_cover_url":"https://i.scdn.co/image/ab6765630000f68d04e0dee31b20519e3fab3e01","position":105553,"duration":1533311,"release_date":"","track_number":0,"disc_number":0}} Dec 07 19:23:50 volumiolego volumio[1069]: SPOTIFY: received: {"type":"active","data":null} Dec 07 19:23:50 volumiolego volumio[1069]: info: Aligning Spotify Volume to Volumio Volume Dec 07 19:23:50 volumiolego volumio[1069]: info: CoreCommandRouter::volumioGetState Dec 07 19:23:50 volumiolego volumio[1069]: info: CorePlayQueue::getTrack 0 Dec 07 19:23:50 volumiolego volumio[1069]: info: Setting Spotify Volume from Volumio: 11 Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 07 19:23:50 volumiolego go-librespot[1467]: time="2025-12-07T19:23:50+01:00" level=trace msg="emitting websocket event: playing" Dec 07 19:23:50 volumiolego volumio[1069]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:show:6LuAZxNcc4sjMoJRhfZI4L","uri":"spotify:episode:6uLqjwWlYcBQkfQixyDgy8","resume":false,"play_origin":"podcast-episode"}} Dec 07 19:23:50 volumiolego volumio[1069]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 19:23:50 volumiolego volumio[1069]: TypeError: Cannot read property 'service' of undefined Dec 07 19:23:50 volumiolego volumio[1069]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 07 19:23:50 volumiolego volumio[1069]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Dec 07 19:23:50 volumiolego volumio[1069]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 07 19:23:50 volumiolego volumio[1069]: at WebSocket.emit (events.js:315:20) Dec 07 19:23:50 volumiolego volumio[1069]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 07 19:23:50 volumiolego volumio[1069]: at Receiver.emit (events.js:315:20) Dec 07 19:23:50 volumiolego volumio[1069]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 07 19:23:50 volumiolego volumio[1069]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 07 19:23:50 volumiolego volumio[1069]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 07 19:23:50 volumiolego volumio[1069]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 07 19:23:50 volumiolego volumio[1069]: at writeOrBuffer (internal/streams/writable.js:358:12) Dec 07 19:23:50 volumiolego volumio[1069]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Dec 07 19:23:50 volumiolego volumio[1069]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 07 19:23:50 volumiolego volumio[1069]: at Socket.emit (events.js:315:20) Dec 07 19:23:50 volumiolego volumio[1069]: at addChunk (internal/streams/readable.js:309:12) Dec 07 19:23:50 volumiolego volumio[1069]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 07 19:23:50 volumiolego volumio[1069]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 19:23:51 volumiolego sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-07 19:22 Dec 07 19:23:51 volumiolego sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 19:23:51 volumiolego go-librespot[1467]: time="2025-12-07T19:23:51+01:00" level=debug msg="handling seek_to player command from bee8610f483eb25f1029e30f2afa00b2e7056dcc" Dec 07 19:23:51 volumiolego go-librespot[1467]: time="2025-12-07T19:23:51+01:00" level=debug msg="seek track to 0ms" Dec 07 19:23:51 volumiolego go-librespot[1467]: time="2025-12-07T19:23:51+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:episode:6uLqjwWlYcBQkfQixyDgy8" PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"