-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-12-01 09:59:26 CET. -- Dec 01 09:59:17 volumiokhv systemd-timedated[1007]: Changed local time to Sun Dec 1 09:59:17 2024 Dec 01 09:59:17 volumiokhv sudo[992]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:17 volumiokhv volumio-time-update[622]: volumio-time-update-util: System time updated successfully. Dec 01 09:59:17 volumiokhv systemd[1]: Started Volumio Time Update Utility. Dec 01 09:59:17 volumiokhv ntpd[764]: Listen normally on 3 wlan0 192.168.1.65:123 Dec 01 09:59:17 volumiokhv ntpd[764]: new interface(s) found: waking up resolver Dec 01 09:59:18 volumiokhv nmbd[734]: [2024/12/01 09:59:18.163808, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 01 09:59:18 volumiokhv systemd[1]: Started Samba NMB Daemon. Dec 01 09:59:18 volumiokhv nmbd[734]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 01 09:59:18 volumiokhv systemd[1]: Starting Samba Winbind Daemon... Dec 01 09:59:18 volumiokhv winbindd[1013]: [2024/12/01 09:59:18.445532, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 01 09:59:18 volumiokhv winbindd[1013]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 01 09:59:18 volumiokhv winbindd[1013]: [2024/12/01 09:59:18.489935, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 01 09:59:18 volumiokhv systemd[1]: Started Samba Winbind Daemon. Dec 01 09:59:18 volumiokhv winbindd[1013]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 01 09:59:18 volumiokhv systemd[1]: Starting Samba SMB Daemon... Dec 01 09:59:18 volumiokhv volumio[995]: info: ------------------------------------------- Dec 01 09:59:18 volumiokhv volumio[995]: info: ----- Volumio3 ---- Dec 01 09:59:18 volumiokhv volumio[995]: info: ------------------------------------------- Dec 01 09:59:18 volumiokhv volumio[995]: info: ----- System startup ---- Dec 01 09:59:18 volumiokhv volumio[995]: info: ------------------------------------------- Dec 01 09:59:18 volumiokhv volumio-remote-updater[596]: [2024-12-01 09:59:18] [connect] Successful connection Dec 01 09:59:18 volumiokhv smbd[1026]: [2024/12/01 09:59:18.901474, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 01 09:59:18 volumiokhv smbd[1026]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 01 09:59:18 volumiokhv systemd[1]: Started Samba SMB Daemon. Dec 01 09:59:18 volumiokhv systemd[1]: Reached target Multi-User System. Dec 01 09:59:18 volumiokhv systemd[1]: Reached target Graphical Interface. Dec 01 09:59:18 volumiokhv systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 01 09:59:19 volumiokhv systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 01 09:59:19 volumiokhv systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 01 09:59:19 volumiokhv systemd[1]: Startup finished in 13.864s (kernel) + 30.035s (userspace) = 43.899s. Dec 01 09:59:19 volumiokhv volumio[995]: info: MYVOLUMIO Environment detected Dec 01 09:59:19 volumiokhv volumio[995]: info: Plugin folders cleanup Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning into folder /volumio/app/plugins/ Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category audio_interface Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category miscellanea Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category music_service Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category plugins.json Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category system_controller Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category user_interface Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning into folder /data/plugins/ Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category audio_interface Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category music_service Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category system_hardware Dec 01 09:59:19 volumiokhv volumio[995]: info: Scanning category user_interface Dec 01 09:59:19 volumiokhv volumio[995]: info: Plugin folders cleanup completed Dec 01 09:59:19 volumiokhv volumio[995]: info: ------------------------------------------- Dec 01 09:59:19 volumiokhv volumio[995]: info: ----- Core plugins startup ---- Dec 01 09:59:19 volumiokhv volumio[995]: info: ------------------------------------------- Dec 01 09:59:19 volumiokhv volumio[995]: info: Loading plugins from folder /volumio/app/plugins/ Dec 01 09:59:19 volumiokhv volumio[995]: info: Adding plugin upnp to MyMusic Plugins Dec 01 09:59:19 volumiokhv volumio[995]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 01 09:59:19 volumiokhv volumio[995]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 01 09:59:19 volumiokhv volumio[995]: info: Loading plugins from folder /data/plugins/ Dec 01 09:59:19 volumiokhv volumio[995]: info: Loading plugin "system"... Dec 01 09:59:19 volumiokhv volumio[995]: info: Loading plugin "appearance"... Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "network"... Dec 01 09:59:20 volumiokhv volumio[995]: info: Refreshing Cached IP Addresses Dec 01 09:59:20 volumiokhv sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 09:59:20 volumiokhv sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:20 volumiokhv sudo[1044]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:20 volumiokhv sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 09:59:20 volumiokhv sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:20 volumiokhv sudo[1046]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "services"... Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "alsa_controller"... Dec 01 09:59:20 volumiokhv sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 01 09:59:20 volumiokhv sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:20 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "wizard"... Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "networkfs"... Dec 01 09:59:20 volumiokhv sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=musicshare,password=oofahSh3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/musicshare /mnt/NAS/Musik Dec 01 09:59:20 volumiokhv sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:20 volumiokhv volumio[995]: info: Starting Udev Watcher for removable devices Dec 01 09:59:20 volumiokhv sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=musicshare,password=oofahSh3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/musicshare_new /mnt/NAS/Musik_neu Dec 01 09:59:20 volumiokhv sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:20 volumiokhv sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=musicshare,password=oofahSh3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/musicshare_hires /mnt/NAS/Musicshare_HiRes Dec 01 09:59:20 volumiokhv sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:20 volumiokhv volumio[995]: info: Ignoring mount for partition: boot Dec 01 09:59:20 volumiokhv volumio[995]: info: Ignoring mount for partition: volumio Dec 01 09:59:20 volumiokhv volumio[995]: info: Ignoring mount for partition: volumio_data Dec 01 09:59:20 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "volumio_command_line_client"... Dec 01 09:59:20 volumiokhv volumio[995]: info: Plugin upnp is not enabled Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "my_music"... Dec 01 09:59:20 volumiokhv volumio[995]: info: Loading plugin "mpd"... Dec 01 09:59:20 volumiokhv kernel: Key type cifs.spnego registered Dec 01 09:59:20 volumiokhv kernel: Key type cifs.idmap registered Dec 01 09:59:20 volumiokhv 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 01 09:59:20 volumiokhv kernel: CIFS: Attempting to mount //192.168.1.15/musicshare_new Dec 01 09:59:21 volumiokhv volumio[995]: info: Plugin upnp_browser is not enabled Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "alarm-clock"... Dec 01 09:59:21 volumiokhv volumio[995]: info: Plugin airplay_emulation is not enabled Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "last_100"... Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "webradio"... Dec 01 09:59:21 volumiokhv sudo[1074]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:21 volumiokhv kernel: CIFS: Attempting to mount //192.168.1.15/musicshare_hires Dec 01 09:59:21 volumiokhv sudo[1077]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "i2s_dacs"... Dec 01 09:59:21 volumiokhv kernel: CIFS: Attempting to mount //192.168.1.15/musicshare Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "volumiodiscovery"... Dec 01 09:59:21 volumiokhv volumio[995]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 09:59:21 volumiokhv volumio[995]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 09:59:21 volumiokhv volumio[995]: *** WARNING *** For more information see Dec 01 09:59:21 volumiokhv node[995]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 09:59:21 volumiokhv volumio[995]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 09:59:21 volumiokhv volumio[995]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 09:59:21 volumiokhv volumio[995]: *** WARNING *** For more information see Dec 01 09:59:21 volumiokhv node[995]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 09:59:21 volumiokhv node[995]: *** WARNING *** For more information see Dec 01 09:59:21 volumiokhv node[995]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 09:59:21 volumiokhv node[995]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 09:59:21 volumiokhv node[995]: *** WARNING *** For more information see Dec 01 09:59:21 volumiokhv volumio[995]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 01 09:59:21 volumiokhv volumio[995]: info: Discovery: Started advertising with name: VolumioKHV Dec 01 09:59:21 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "gpio-buttons"... Dec 01 09:59:21 volumiokhv sudo[1072]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:21 volumiokhv volumio[995]: info: GPIO-Buttons initialized Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "outputs"... Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "albumart"... Dec 01 09:59:21 volumiokhv volumio[995]: info: Plugin example_plugin is not enabled Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "inputs"... Dec 01 09:59:21 volumiokhv volumio[995]: info: Loading plugin "updater_comm"... Dec 01 09:59:22 volumiokhv volumio[995]: info: Plugin mpdemulation is not enabled Dec 01 09:59:22 volumiokhv volumio[995]: info: Loading plugin "rest_api"... Dec 01 09:59:22 volumiokhv volumio[995]: info: Loading plugin "websocket"... Dec 01 09:59:22 volumiokhv volumio[995]: info: Starting Socket.io Server version 2.3.0 Dec 01 09:59:22 volumiokhv volumio[995]: info: Loading plugin "fusiondsp"... Dec 01 09:59:22 volumiokhv volumio[995]: Forking 3 albumart workers Dec 01 09:59:22 volumiokhv volumio[995]: info: Applying required configuration parameters for plugin fusiondsp Dec 01 09:59:22 volumiokhv volumio[995]: Starting albumart workers Dec 01 09:59:22 volumiokhv volumio[995]: info: Loading plugin "podcast"... Dec 01 09:59:22 volumiokhv volumio[995]: Starting albumart workers Dec 01 09:59:22 volumiokhv volumio[995]: Starting albumart workers Dec 01 09:59:22 volumiokhv systemd[1]: systemd-fsckd.service: Succeeded. Dec 01 09:59:22 volumiokhv volumio[995]: info: ControllerPodcast::constructor Dec 01 09:59:23 volumiokhv volumio[995]: info: Loading plugin "Systeminfo"... Dec 01 09:59:23 volumiokhv volumio[995]: info: Loading i18n strings for locale de Dec 01 09:59:23 volumiokhv volumio[995]: Updating browse sources language Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::initPlayerControls Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: Express server listening on port 3000 Dec 01 09:59:23 volumiokhv volumio[995]: [Metrics] WebUI: 5s 761.89ms Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::resetVolumioState Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::getcurrentVolume Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 09:59:23 volumiokhv volumio[995]: info: Volumio Network Manager: Network status updated: 2 Dec 01 09:59:23 volumiokhv volumio-remote-updater[596]: [2024-12-01 09:59:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1733043558 101 Dec 01 09:59:23 volumiokhv volumio[995]: 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 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 09:59:23 volumiokhv volumio[995]: info: Discovery: Getting this device information Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::volumioGetState Dec 01 09:59:23 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 09:59:23 volumiokhv volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 09:59:23 volumiokhv volumio[995]: info: Executing start script for DAC IQaudIO DAC Plus Dec 01 09:59:23 volumiokhv volumio[995]: info: Reloading queue from file Dec 01 09:59:23 volumiokhv volumio[995]: info: VolumeController:: Volume=86 Mute =false Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::pushState Dec 01 09:59:23 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::volumioPushState Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::updateTrackBlock Dec 01 09:59:23 volumiokhv volumio[995]: info: CorePlayQueue::getTrackBlock Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::setRepeat null single undefined Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::pushState Dec 01 09:59:23 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::volumioPushState Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::setRandom null Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreStateMachine::pushState Dec 01 09:59:23 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:23 volumiokhv volumio[995]: info: CoreCommandRouter::volumioPushState Dec 01 09:59:23 volumiokhv volumio[995]: info: Setting Device type: Raspberry PI Dec 01 09:59:23 volumiokhv volumio[995]: info: DAC script executed Dec 01 09:59:24 volumiokhv volumio[995]: info: Completed loading Core Plugins Dec 01 09:59:24 volumiokhv volumio[995]: info: Preparing to generate the ALSA configuration file Dec 01 09:59:24 volumiokhv volumio[995]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 01 09:59:24 volumiokhv volumio[995]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Dec 01 09:59:24 volumiokhv volumio[995]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 01 09:59:24 volumiokhv volumio[995]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 01 09:59:24 volumiokhv volumio[995]: info: Reading ALSA contributions from plugins. Dec 01 09:59:24 volumiokhv volumio[995]: info: Discovery: adding e0ed2b2d-5c9b-4828-8bd9-ec133ab8cc1b Dec 01 09:59:24 volumiokhv volumio[995]: info: Discovery: Found device VolumioKHV Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::volumioGetState Dec 01 09:59:24 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:24 volumiokhv volumio[995]: info: VolumeController:: Volume=86 Mute =false Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreStateMachine::pushState Dec 01 09:59:24 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::volumioPushState Dec 01 09:59:24 volumiokhv volumio[995]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.202 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 01 09:59:24 volumiokhv volumio[995]: info: Asound.conf file unchanged, so no further update is needed Dec 01 09:59:24 volumiokhv volumio[995]: info: Output device has changed, restarting MPD Dec 01 09:59:24 volumiokhv sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 09:59:24 volumiokhv volumio[995]: info: ___________ START PLUGINS ___________ Dec 01 09:59:24 volumiokhv sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv volumio[995]: info: ControllerMpd::onStart: Initializing MPD Dec 01 09:59:24 volumiokhv volumio[995]: info: Creating MPD Configuration file Dec 01 09:59:24 volumiokhv sudo[1182]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:24 volumiokhv sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 09:59:24 volumiokhv sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv systemd[1]: Stopping Music Player Daemon... Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 09:59:24 volumiokhv volumio[995]: info: [1733043564153] CoreMusicLibrary::Adding element Last_100 Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 09:59:24 volumiokhv sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 09:59:24 volumiokhv sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv sudo[1188]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 09:59:24 volumiokhv volumio[995]: info: [1733043564160] CoreMusicLibrary::Adding element Webradio Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 09:59:24 volumiokhv volumio[995]: info: Initializing BBC Radios Dec 01 09:59:24 volumiokhv sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 09:59:24 volumiokhv sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: GPIO-Buttons: Reading config and creating triggers... Dec 01 09:59:24 volumiokhv volumio[995]: info: GPIO-Buttons: playPause on pin 17 Dec 01 09:59:24 volumiokhv volumio[995]: error: Plugin gpio-buttons failed to start! Error: EINVAL: invalid argument, write Dec 01 09:59:24 volumiokhv volumio[995]: info: Loading i18n strings for locale de Dec 01 09:59:24 volumiokhv kernel: export_store: invalid GPIO 17 Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: FusionDsp - mixtype--------------------- Hardware Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 09:59:24 volumiokhv volumio[995]: info: [1733043564250] CoreMusicLibrary::Adding element Podcast Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 09:59:24 volumiokhv volumio[995]: Cannot find translation for source Podcast Dec 01 09:59:24 volumiokhv volumio[995]: info: Volumio Calling Home Dec 01 09:59:24 volumiokhv systemd[1]: mpd.service: Succeeded. Dec 01 09:59:24 volumiokhv systemd[1]: Stopped Music Player Daemon. Dec 01 09:59:24 volumiokhv volumio[995]: info: Preparing to generate the ALSA configuration file Dec 01 09:59:24 volumiokhv systemd[1]: Starting Music Player Daemon... Dec 01 09:59:24 volumiokhv volumio[995]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 01 09:59:24 volumiokhv sudo[1204]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 09:59:24 volumiokhv volumio[995]: info: Reading ALSA contributions from plugins. Dec 01 09:59:24 volumiokhv sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv sudo[1204]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 01 09:59:24 volumiokhv sudo[1204]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::volumioGetState Dec 01 09:59:24 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 01 09:59:24 volumiokhv volumio[995]: info: MPD Permissions set Dec 01 09:59:24 volumiokhv volumio[995]: info: MPD Permissions set Dec 01 09:59:24 volumiokhv volumio[995]: 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 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::volumioGetState Dec 01 09:59:24 volumiokhv volumio[995]: info: CorePlayQueue::getTrack 0 Dec 01 09:59:24 volumiokhv volumio[995]: info: Asound.conf file unchanged, so no further update is needed Dec 01 09:59:24 volumiokhv volumio[995]: info: Output device has changed, restarting MPD Dec 01 09:59:24 volumiokhv sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 09:59:24 volumiokhv sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv sudo[1218]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 09:59:24 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:24 volumiokhv systemd[1]: mpd.service: Succeeded. Dec 01 09:59:24 volumiokhv systemd[1]: Stopped Music Player Daemon. Dec 01 09:59:24 volumiokhv volumio[995]: info: MPD Permissions set Dec 01 09:59:24 volumiokhv systemd[1]: Starting Music Player Daemon... Dec 01 09:59:24 volumiokhv sudo[1055]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:24 volumiokhv volumio[995]: info: Volumio called home Dec 01 09:59:24 volumiokhv sudo[1227]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 09:59:24 volumiokhv sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:24 volumiokhv sudo[1227]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 01 09:59:24 volumiokhv sudo[1227]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:25 volumiokhv sh[503]: timed out Dec 01 09:59:25 volumiokhv dhcpcd[553]: timed out Dec 01 09:59:25 volumiokhv sh[503]: dhcpcd exited Dec 01 09:59:25 volumiokhv dhcpcd[553]: dhcpcd exited Dec 01 09:59:25 volumiokhv sh[503]: ifup: failed to bring up eth0 Dec 01 09:59:25 volumiokhv systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 01 09:59:25 volumiokhv systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 01 09:59:25 volumiokhv mpd[1229]: Dec 01 09:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 09:59:25 volumiokhv systemd[1]: Started Music Player Daemon. Dec 01 09:59:25 volumiokhv sudo[1184]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:25 volumiokhv sudo[1220]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:25 volumiokhv sudo[1191]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:25 volumiokhv volumio[995]: info: Completed starting Core Plugins Dec 01 09:59:25 volumiokhv volumio[995]: info: ------------------------------------------- Dec 01 09:59:25 volumiokhv volumio[995]: info: ----- MyVolumio plugins startup ---- Dec 01 09:59:25 volumiokhv volumio[995]: info: ------------------------------------------- Dec 01 09:59:25 volumiokhv volumio[995]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 01 09:59:25 volumiokhv volumio[995]: info: MPD running with PID1229 Dec 01 09:59:25 volumiokhv volumio[995]: ,establishing connection Dec 01 09:59:25 volumiokhv volumio[995]: error: MPD error: The expression evaluated to a falsy value: Dec 01 09:59:25 volumiokhv volumio[995]: assert.ok(self.idling) Dec 01 09:59:25 volumiokhv volumio[995]: error: The expression evaluated to a falsy value: Dec 01 09:59:25 volumiokhv volumio[995]: assert.ok(self.idling) Dec 01 09:59:25 volumiokhv volumio[995]: error: MPD error: The expression evaluated to a falsy value: Dec 01 09:59:25 volumiokhv volumio[995]: assert.ok(self.idling) Dec 01 09:59:25 volumiokhv volumio[995]: error: The expression evaluated to a falsy value: Dec 01 09:59:25 volumiokhv volumio[995]: assert.ok(self.idling) Dec 01 09:59:25 volumiokhv volumio[995]: error: MPD error: The expression evaluated to a falsy value: Dec 01 09:59:25 volumiokhv volumio[995]: assert.ok(self.idling) Dec 01 09:59:25 volumiokhv volumio[995]: error: The expression evaluated to a falsy value: Dec 01 09:59:25 volumiokhv volumio[995]: assert.ok(self.idling) Dec 01 09:59:25 volumiokhv volumio[995]: error: updateQueue error: null Dec 01 09:59:26 volumiokhv volumio[995]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Dec 01 09:59:26 volumiokhv volumio[995]: info: camilladsp service started and running in background, instance 1 Dec 01 09:59:26 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 09:59:26 volumiokhv volumio[995]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 01 09:59:26 volumiokhv volumio[995]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 01 09:59:26 volumiokhv volumio[995]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 01 09:59:26 volumiokhv volumio[995]: info: FusionDsp loaded Dec 01 09:59:26 volumiokhv volumio[995]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 09:59:26 volumiokhv sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 01 09:59:26 volumiokhv sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 09:59:26 volumiokhv volumio[995]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 01 09:59:26 volumiokhv volumio[995]: info: Adding Signal Path Element [object Object] Dec 01 09:59:26 volumiokhv volumio[995]: info: Adding fusiondspeq DSP Signal Path Element Dec 01 09:59:26 volumiokhv volumio[995]: info: FusionDsp - ---- installed callbackRead Dec 01 09:59:26 volumiokhv volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 09:59:26 volumiokhv volumio[995]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 01 09:59:26 volumiokhv volumio[995]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Dec 01 09:59:26 volumiokhv volumio[995]: at onErrorNT (internal/child_process.js:465:16) Dec 01 09:59:26 volumiokhv volumio[995]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Dec 01 09:59:26 volumiokhv volumio[995]: errno: -2, Dec 01 09:59:26 volumiokhv volumio[995]: code: 'ENOENT', Dec 01 09:59:26 volumiokhv volumio[995]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 01 09:59:26 volumiokhv volumio[995]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 01 09:59:26 volumiokhv volumio[995]: spawnargs: [ Dec 01 09:59:26 volumiokhv volumio[995]: '-p', Dec 01 09:59:26 volumiokhv volumio[995]: 9876, Dec 01 09:59:26 volumiokhv volumio[995]: '-o', Dec 01 09:59:26 volumiokhv volumio[995]: '/tmp/camilladsp.log', Dec 01 09:59:26 volumiokhv volumio[995]: '-l', Dec 01 09:59:26 volumiokhv volumio[995]: 'warn', Dec 01 09:59:26 volumiokhv volumio[995]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 01 09:59:26 volumiokhv volumio[995]: ] Dec 01 09:59:26 volumiokhv volumio[995]: } Dec 01 09:59:26 volumiokhv volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 09:59:26 volumiokhv systemd[1]: Started FusionDsp Daemon. Dec 01 09:59:26 volumiokhv sudo[1246]: pam_unix(sudo:session): session closed for user root Dec 01 09:59:26 volumiokhv volumio[1248]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 01 09:59:26 volumiokhv systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 01 09:59:26 volumiokhv systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 01 09:59:26 volumiokhv sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 09:58 Dec 01 09:59:26 volumiokhv sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"