-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-29 20:33:36 CEST. -- May 29 20:32:00 rasptouch volumio[1176]: info: Plugin folders cleanup May 29 20:32:00 rasptouch volumio[1176]: info: Scanning into folder /volumio/app/plugins/ May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category audio_interface May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category miscellanea May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category music_service May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category plugins.json May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category system_controller May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category user_interface May 29 20:32:00 rasptouch volumio[1176]: info: Scanning into folder /data/plugins/ May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category system_hardware May 29 20:32:00 rasptouch volumio[1176]: info: Scanning category user_interface May 29 20:32:00 rasptouch volumio[1176]: info: Plugin folders cleanup completed May 29 20:32:00 rasptouch volumio[1176]: info: ------------------------------------------- May 29 20:32:00 rasptouch volumio[1176]: info: ----- Core plugins startup ---- May 29 20:32:00 rasptouch volumio[1176]: info: ------------------------------------------- May 29 20:32:00 rasptouch volumio[1176]: info: Loading plugins from folder /volumio/app/plugins/ May 29 20:32:00 rasptouch volumio[1176]: info: Adding plugin upnp to MyMusic Plugins May 29 20:32:00 rasptouch volumio[1176]: info: Adding plugin airplay_emulation to MyMusic Plugins May 29 20:32:00 rasptouch volumio[1176]: info: Adding plugin upnp_browser to MyMusic Plugins May 29 20:32:00 rasptouch volumio[1176]: info: Loading plugins from folder /data/plugins/ May 29 20:32:00 rasptouch volumio[1176]: info: Loading plugin "system"... May 29 20:32:00 rasptouch volumio[1176]: info: Loading plugin "appearance"... May 29 20:32:01 rasptouch systemd[1]: systemd-hostnamed.service: Succeeded. May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "network"... May 29 20:32:01 rasptouch volumio[1176]: info: Refreshing Cached IP Addresses May 29 20:32:01 rasptouch sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:32:01 rasptouch sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:01 rasptouch sudo[1211]: pam_unix(sudo:session): session closed for user root May 29 20:32:01 rasptouch sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 20:32:01 rasptouch sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:01 rasptouch sudo[1213]: pam_unix(sudo:session): session closed for user root May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "services"... May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "alsa_controller"... May 29 20:32:01 rasptouch sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 29 20:32:01 rasptouch sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:01 rasptouch sudo[1222]: pam_unix(sudo:session): session closed for user root May 29 20:32:01 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "wizard"... May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "networkfs"... May 29 20:32:01 rasptouch volumio[1176]: info: Starting Udev Watcher for removable devices May 29 20:32:01 rasptouch sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=VnG,password=QWsxF_wLW#D2tR_i,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.21/Music /mnt/NAS/Alphabet May 29 20:32:01 rasptouch sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:01 rasptouch volumio[1176]: info: Ignoring mount for partition: boot May 29 20:32:01 rasptouch volumio[1176]: info: Ignoring mount for partition: volumio May 29 20:32:01 rasptouch volumio[1176]: info: Ignoring mount for partition: volumio_data May 29 20:32:01 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "volumio_command_line_client"... May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "upnp"... May 29 20:32:01 rasptouch volumio[1176]: info: [1748543521291] Starting Upmpd Daemon May 29 20:32:01 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "my_music"... May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "mpd"... May 29 20:32:01 rasptouch kernel: Key type cifs.spnego registered May 29 20:32:01 rasptouch kernel: Key type cifs.idmap registered May 29 20:32:01 rasptouch 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. May 29 20:32:01 rasptouch kernel: CIFS: Attempting to mount //192.168.1.21/Music May 29 20:32:01 rasptouch kernel: cryptd: max_cpu_qlen set to 1000 May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "upnp_browser"... May 29 20:32:01 rasptouch sudo[1239]: pam_unix(sudo:session): session closed for user root May 29 20:32:01 rasptouch volumio[1176]: info: Loading plugin "alarm-clock"... May 29 20:32:02 rasptouch volumio-remote-updater[661]: [2025-05-29 20:32:02] [connect] Successful connection May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "airplay_emulation"... May 29 20:32:02 rasptouch volumio[1176]: info: Starting Shairport Sync May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "last_100"... May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "webradio"... May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "i2s_dacs"... May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "volumiodiscovery"... May 29 20:32:02 rasptouch volumio[1176]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 20:32:02 rasptouch volumio[1176]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 20:32:02 rasptouch node[1176]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 20:32:02 rasptouch volumio[1176]: *** WARNING *** For more information see May 29 20:32:02 rasptouch volumio[1176]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 20:32:02 rasptouch volumio[1176]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 20:32:02 rasptouch volumio[1176]: *** WARNING *** For more information see May 29 20:32:02 rasptouch node[1176]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 20:32:02 rasptouch node[1176]: *** WARNING *** For more information see May 29 20:32:02 rasptouch node[1176]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 20:32:02 rasptouch node[1176]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 20:32:02 rasptouch node[1176]: *** WARNING *** For more information see May 29 20:32:02 rasptouch volumio[1176]: info: Applying required configuration parameters for plugin volumiodiscovery May 29 20:32:02 rasptouch volumio[1176]: info: Discovery: Started advertising with name: Rasptouch May 29 20:32:02 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "outputs"... May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "albumart"... May 29 20:32:02 rasptouch volumio[1176]: info: Plugin example_plugin is not enabled May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "inputs"... May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "updater_comm"... May 29 20:32:02 rasptouch volumio[1176]: info: Plugin mpdemulation is not enabled May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "rest_api"... May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "websocket"... May 29 20:32:02 rasptouch volumio[1176]: info: Starting Socket.io Server version 2.3.0 May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "audiophonics_evo_sabre"... May 29 20:32:02 rasptouch volumio[1176]: Forking 3 albumart workers May 29 20:32:02 rasptouch volumio[1176]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre May 29 20:32:02 rasptouch volumio[1176]: info: Loading plugin "audiophonicsonoff"... May 29 20:32:03 rasptouch volumio[1176]: Starting albumart workers May 29 20:32:03 rasptouch volumio[1176]: Starting albumart workers May 29 20:32:03 rasptouch volumio[1176]: Starting albumart workers May 29 20:32:03 rasptouch volumio[1176]: info: Applying required configuration parameters for plugin audiophonicsonoff May 29 20:32:03 rasptouch volumio[1176]: info: Audiophonics on/off initiated May 29 20:32:03 rasptouch volumio[1176]: info: Plugin touch_display is not enabled May 29 20:32:03 rasptouch volumio[1176]: info: Loading i18n strings for locale en May 29 20:32:03 rasptouch volumio[1176]: Updating browse sources language May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::initPlayerControls May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:32:03 rasptouch volumio[1176]: Express server listening on port 3000 May 29 20:32:03 rasptouch volumio[1176]: [Metrics] WebUI: 5s 107.93ms May 29 20:32:03 rasptouch volumio[1176]: info: CoreStateMachine::resetVolumioState May 29 20:32:03 rasptouch volumio[1176]: info: CoreStateMachine::getcurrentVolume May 29 20:32:03 rasptouch volumio[1176]: info: CoreCommandRouter::volumioRetrievevolume May 29 20:32:03 rasptouch volumio[1176]: info: Volumio Network Manager: Network status updated: 1 May 29 20:32:03 rasptouch volumio[1176]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 29 20:32:03 rasptouch volumio[1176]: wlan0 Interface doesn't support scanning : Device or resource busy May 29 20:32:03 rasptouch volumio[1176]: info: Cannot use regular scanning, forcing with ap-force May 29 20:32:03 rasptouch sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 29 20:32:03 rasptouch sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:09 rasptouch sudo[1337]: pam_unix(sudo:session): session closed for user root May 29 20:32:10 rasptouch systemd[1]: systemd-timedated.service: Succeeded. May 29 20:32:10 rasptouch volumio[1176]: info: Reloading queue from file May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: Setting Device type: Raspberry PI May 29 20:32:10 rasptouch volumio[1176]: info: Completed loading Core Plugins May 29 20:32:10 rasptouch volumio[1176]: info: Preparing to generate the ALSA configuration file May 29 20:32:10 rasptouch volumio[1176]: info: VolumeController:: Volume=100 Mute =true May 29 20:32:10 rasptouch volumio[1176]: info: CoreStateMachine::pushState May 29 20:32:10 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioPushState May 29 20:32:10 rasptouch volumio[1176]: info: CoreStateMachine::updateTrackBlock May 29 20:32:10 rasptouch volumio[1176]: info: CorePlayQueue::getTrackBlock May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioRetrievevolume May 29 20:32:10 rasptouch volumio[1176]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 29 20:32:10 rasptouch volumio[1176]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 May 29 20:32:10 rasptouch volumio[1176]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 29 20:32:10 rasptouch volumio[1176]: info: CoreStateMachine::setRepeat null single undefined May 29 20:32:10 rasptouch volumio[1176]: info: CoreStateMachine::pushState May 29 20:32:10 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioPushState May 29 20:32:10 rasptouch volumio[1176]: info: CoreStateMachine::setRandom null May 29 20:32:10 rasptouch volumio[1176]: info: CoreStateMachine::pushState May 29 20:32:10 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioPushState May 29 20:32:10 rasptouch volumio[1176]: info: Asound.conf file written May 29 20:32:10 rasptouch sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 29 20:32:10 rasptouch sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch sudo[1349]: pam_unix(sudo:session): session closed for user root May 29 20:32:10 rasptouch volumio[1176]: info: Output device has changed, restarting MPD May 29 20:32:10 rasptouch volumio[1176]: info: Output device has changed, restarting Shairport Sync May 29 20:32:10 rasptouch sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:10 rasptouch sudo[1354]: pam_unix(sudo:session): session closed for user root May 29 20:32:10 rasptouch sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 20:32:10 rasptouch sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch systemd[1]: Stopping Music Player Daemon... May 29 20:32:10 rasptouch volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 20:32:10 rasptouch volumio[1176]: info: ___________ START PLUGINS ___________ May 29 20:32:10 rasptouch volumio[1176]: info: ControllerMpd::onStart: Initializing MPD May 29 20:32:10 rasptouch volumio[1176]: info: Creating MPD Configuration file May 29 20:32:10 rasptouch sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 20:32:10 rasptouch sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 20:32:10 rasptouch volumio[1176]: info: [1748543530285] CoreMusicLibrary::Adding element Media Servers May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:32:10 rasptouch sudo[1363]: pam_unix(sudo:session): session closed for user root May 29 20:32:10 rasptouch sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 20:32:10 rasptouch sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 20:32:10 rasptouch volumio[1176]: info: [1748543530379] CoreMusicLibrary::Adding element Last_100 May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 20:32:10 rasptouch volumio[1176]: info: [1748543530381] CoreMusicLibrary::Adding element Webradio May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:32:10 rasptouch volumio[1176]: info: Initializing BBC Radios May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: EVO SABRE : Starting Plugin May 29 20:32:10 rasptouch volumio[1176]: info: Loading i18n strings for locale en May 29 20:32:10 rasptouch volumio[1176]: info: Configuring GPIO pins May 29 20:32:10 rasptouch volumio[1176]: error: Plugin audiophonicsonoff failed to start! Error: EBUSY: resource busy or locked, write May 29 20:32:10 rasptouch volumio[1176]: info: Volumio Calling Home May 29 20:32:10 rasptouch systemd[1]: mpd.service: Succeeded. May 29 20:32:10 rasptouch systemd[1]: Stopped Music Player Daemon. May 29 20:32:10 rasptouch systemd[1]: Starting Music Player Daemon... May 29 20:32:10 rasptouch volumio[1176]: info: Discovery: adding c077923a-0de3-479d-a976-9cf9d070221d May 29 20:32:10 rasptouch volumio[1176]: info: Discovery: Found device Rasptouch May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:10 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:10 rasptouch volumio[1176]: info: MPD Permissions set May 29 20:32:10 rasptouch sudo[1393]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 20:32:10 rasptouch sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch sudo[1393]: pam_unix(sudo:session): session closed for user root May 29 20:32:10 rasptouch sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 29 20:32:10 rasptouch sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 29 20:32:10 rasptouch systemd[1]: Reloading. May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:10 rasptouch volumio[1176]: info: Volumio called home May 29 20:32:10 rasptouch volumio[1176]: info: VolumeController:: Volume=100 Mute =true May 29 20:32:10 rasptouch volumio[1176]: info: CoreStateMachine::pushState May 29 20:32:10 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioPushState May 29 20:32:10 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:10 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:10 rasptouch volumio[1176]: info: Starting Shairport Sync May 29 20:32:10 rasptouch volumio[1176]: info: Starting Shairport Sync May 29 20:32:10 rasptouch volumio[1176]: info: Starting Shairport Sync May 29 20:32:10 rasptouch sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 20:32:10 rasptouch sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 20:32:10 rasptouch sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:10 rasptouch sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 20:32:10 rasptouch sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:11 rasptouch 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. May 29 20:32:11 rasptouch sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:32:11 rasptouch sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:11 rasptouch sudo[1446]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 20:32:11 rasptouch sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:11 rasptouch sudo[1448]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch sudo[1396]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch volumio[1176]: info: EVO SABRE : systemd cmd daemon-reload : success May 29 20:32:11 rasptouch sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 20:32:11 rasptouch sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:11 rasptouch sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service May 29 20:32:11 rasptouch sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:11 rasptouch systemd[1]: Started UPnP Renderer front-end to MPD. May 29 20:32:11 rasptouch systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 20:32:11 rasptouch systemd[1]: shairport-sync.service: Succeeded. May 29 20:32:11 rasptouch systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 20:32:11 rasptouch sudo[1452]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch volumio[1176]: info: Upmpdcli Daemon Started May 29 20:32:11 rasptouch systemd[1]: Started OLED Display Service for EVO SABRE. May 29 20:32:11 rasptouch systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 20:32:11 rasptouch sudo[1459]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio May 29 20:32:11 rasptouch sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:11 rasptouch sudo[1425]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch sudo[1428]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch sudo[1423]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch sudo[1455]: pam_unix(sudo:session): session closed for user root May 29 20:32:11 rasptouch volumio[1176]: info: Shairport-Sync Started May 29 20:32:11 rasptouch volumio[1176]: Error adding Membership: Error: addMembership EINVAL May 29 20:32:11 rasptouch volumio[1176]: info: Shairport-Sync Started May 29 20:32:11 rasptouch volumio[1176]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success May 29 20:32:11 rasptouch volumio[1176]: info: Shairport-Sync Started May 29 20:32:11 rasptouch mpd[1399]: May 29 20:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 20:32:11 rasptouch sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service May 29 20:32:11 rasptouch sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:11 rasptouch systemd[1]: Starting lircd(8) initialization helper tool... May 29 20:32:12 rasptouch lircd-setup[1473]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 29 20:32:12 rasptouch lircd-setup[1473]: parser = configparser.SafeConfigParser() May 29 20:32:12 rasptouch systemd[1]: lircd-setup.service: Succeeded. May 29 20:32:12 rasptouch systemd[1]: Started lircd(8) initialization helper tool. May 29 20:32:12 rasptouch systemd[1]: Started evo_remote.service. May 29 20:32:12 rasptouch sudo[1469]: pam_unix(sudo:session): session closed for user root May 29 20:32:12 rasptouch volumio[1176]: info: EVO SABRE : systemd cmd restart evo_remote.service : success May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Info: lircd: Opening log, level: Info May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Version: lircd 0.10.1 May 29 20:32:12 rasptouch sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service May 29 20:32:12 rasptouch sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: System info: Linux rasptouch 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux May 29 20:32:12 rasptouch systemd[1]: Starting lircd(8) initialization helper tool... May 29 20:32:12 rasptouch lircd-setup[1480]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. May 29 20:32:12 rasptouch lircd-setup[1480]: parser = configparser.SafeConfigParser() May 29 20:32:12 rasptouch systemd[1]: lircd-setup.service: Succeeded. May 29 20:32:12 rasptouch systemd[1]: Started lircd(8) initialization helper tool. May 29 20:32:12 rasptouch systemd[1]: Started evo_irexec.service. May 29 20:32:12 rasptouch sudo[1476]: pam_unix(sudo:session): session closed for user root May 29 20:32:12 rasptouch volumio[1176]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Info: Initial device: /dev/lirc0 May 29 20:32:12 rasptouch lircd[1474]: lircd-0.10.1[1474]: Info: lircd: Opening log, level: Info May 29 20:32:12 rasptouch lircd[1474]: lircd-0.10.1[1474]: Warning: Running as root May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Info: Initial device: /dev/lirc0 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Info: [lirc] protocol is enabled May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: driver: default May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: output: /var/run/lirc/lircd May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: nodaemon: 1 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: logfile: syslog May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: immediate-init: 0 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: permission: 666 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: driver-options: May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: listen: 0 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: connect: (null) May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: userelease: 0 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: effective_user: (null) May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: release_suffix: _EVUP May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: allow_simulate: 0 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: repeat_max: 600 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Options: dynamic_codes: (null) May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Current driver: default May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Driver API version: 3 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Driver version: 0.10.0 May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Info: lircd: Opening log, level: Info May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Warning: Running as root May 29 20:32:12 rasptouch lircd[1474]: lircd-0.10.1[1474]: Info: Using remote: ApEvo. May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Info: Using remote: ApEvo. May 29 20:32:12 rasptouch lircd[1474]: lircd-0.10.1[1474]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 29 20:32:12 rasptouch lircd[1474]: lircd-0.10.1[1474]: Notice: accepted new client on /var/run/lirc/lircd May 29 20:32:12 rasptouch lircd[1474]: lircd-0.10.1[1474]: Info: [lirc] protocol is enabled May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Notice: accepted new client on /var/run/lirc/lircd May 29 20:32:12 rasptouch lircd-0.10.1[1474]: Info: [lirc] protocol is enabled May 29 20:32:12 rasptouch systemd[1]: Started Music Player Daemon. May 29 20:32:12 rasptouch sudo[1365]: pam_unix(sudo:session): session closed for user root May 29 20:32:12 rasptouch sudo[1356]: pam_unix(sudo:session): session closed for user root May 29 20:32:12 rasptouch volumio[1176]: info: Completed starting Core Plugins May 29 20:32:12 rasptouch volumio[1176]: info: ------------------------------------------- May 29 20:32:12 rasptouch volumio[1176]: info: ----- MyVolumio plugins startup ---- May 29 20:32:12 rasptouch volumio[1176]: info: ------------------------------------------- May 29 20:32:12 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Fetching plans data.... May 29 20:32:12 rasptouch volumio[1176]: info: MPD running with PID1399 May 29 20:32:12 rasptouch volumio[1176]: ,establishing connection May 29 20:32:12 rasptouch volumio[1176]: error: MPD error: The expression evaluated to a falsy value: May 29 20:32:12 rasptouch volumio[1176]: assert.ok(self.idling) May 29 20:32:12 rasptouch volumio[1176]: error: The expression evaluated to a falsy value: May 29 20:32:12 rasptouch volumio[1176]: assert.ok(self.idling) May 29 20:32:12 rasptouch volumio[1176]: error: MPD error: The expression evaluated to a falsy value: May 29 20:32:12 rasptouch volumio[1176]: assert.ok(self.idling) May 29 20:32:12 rasptouch volumio[1176]: error: The expression evaluated to a falsy value: May 29 20:32:12 rasptouch volumio[1176]: assert.ok(self.idling) May 29 20:32:12 rasptouch volumio[1176]: error: updateQueue error: null May 29 20:32:12 rasptouch volumio[1457]: Generating RSA private key, 4096 bit long modulus (2 primes) May 29 20:32:13 rasptouch volumio[1457]: ........++++ May 29 20:32:13 rasptouch volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 29 20:32:13 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:13 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:13 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetQueue May 29 20:32:13 rasptouch volumio[1176]: info: CoreStateMachine::getQueue May 29 20:32:13 rasptouch volumio[1176]: info: CorePlayQueue::getQueue May 29 20:32:13 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:13 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:14 rasptouch wpa_supplicant[908]: wlan0: Trying to associate with SSID 'Proximus-Home-2B68' May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: carrier acquired May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: connected to Access Point `Proximus-Home-2B68' May 29 20:32:14 rasptouch wpa_supplicant[908]: wlan0: Associated with 9c:97:26:6a:92:31 May 29 20:32:14 rasptouch wpa_supplicant[908]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:97:26:6a:92:31 completed [id=0 id_str=] May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: IAID 67:07:c6:30 May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: adding address fe80::19b4:5cd2:67a:15e5 May 29 20:32:14 rasptouch dhcpcd[1078]: ipv6_addaddr1: Permission denied May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: carrier lost May 29 20:32:14 rasptouch wpa_supplicant[908]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: carrier acquired May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: IAID 67:07:c6:30 May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: rebinding lease of 192.168.1.62 May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: probing address 192.168.1.62/24 May 29 20:32:14 rasptouch dhcpcd[1078]: wlan0: soliciting an IPv6 router May 29 20:32:16 rasptouch volumio[1457]: ...........................++++ May 29 20:32:16 rasptouch volumio[1457]: e is 65537 (0x010001) May 29 20:32:16 rasptouch volumio[1457]: writing RSA key May 29 20:32:17 rasptouch volumio-remote-updater[661]: [2025-05-29 20:32:17] [connect] Successful connection May 29 20:32:17 rasptouch volumio-remote-updater[661]: [2025-05-29 20:32:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748543537 101 May 29 20:32:17 rasptouch volumio[1176]: 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: 3 May 29 20:32:18 rasptouch volumio[1176]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 29 20:32:20 rasptouch dhcpcd[1078]: wlan0: leased 192.168.1.62 for 3600 seconds May 29 20:32:20 rasptouch avahi-daemon[670]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.62. May 29 20:32:20 rasptouch dhcpcd[1078]: wlan0: adding route to 192.168.1.0/24 May 29 20:32:20 rasptouch dhcpcd[1078]: wlan0: adding default route via 192.168.1.1 May 29 20:32:20 rasptouch avahi-daemon[670]: New relevant interface wlan0.IPv4 for mDNS. May 29 20:32:20 rasptouch avahi-daemon[670]: Registering new address record for 192.168.1.62 on wlan0.IPv4. May 29 20:32:21 rasptouch volumio[1176]: info: Volumio Network Manager: Network status updated: 3 May 29 20:32:21 rasptouch ntpd[818]: Listen normally on 4 wlan0 192.168.1.62:123 May 29 20:32:21 rasptouch ntpd[818]: new interface(s) found: waking up resolver May 29 20:32:21 rasptouch volumio[1176]: info: Discovery: this is already registered, c077923a-0de3-479d-a976-9cf9d070221d May 29 20:32:21 rasptouch volumio[1176]: info: Discovery: Found device Rasptouch May 29 20:32:21 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:21 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 29 20:32:22 rasptouch volumio[1176]: info: Adding plugin bluetooth to MyMusic Plugins May 29 20:32:22 rasptouch volumio[1176]: info: Adding plugin multiroom to MyMusic Plugins May 29 20:32:22 rasptouch volumio[1176]: info: Adding plugin metavolumio to MyMusic Plugins May 29 20:32:22 rasptouch volumio[1176]: info: Adding plugin cd_controller to MyMusic Plugins May 29 20:32:22 rasptouch volumio[1176]: info: Adding plugin qobuzconnect to MyMusic Plugins May 29 20:32:22 rasptouch volumio[1176]: info: Adding plugin smart_inputs to MyMusic Plugins May 29 20:32:22 rasptouch volumio[1176]: info: Adding plugin tidalconnect to MyMusic Plugins May 29 20:32:22 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 29 20:32:23 rasptouch volumio[1176]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 20:32:23 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 20:32:23 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:23 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:23 rasptouch volumio[1176]: info: Starting MyVolumio Remote Streaming Endpoints May 29 20:32:23 rasptouch volumio[1176]: info: MyVolumio login type: Token May 29 20:32:23 rasptouch volumio[1176]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 20:32:23 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 20:32:24 rasptouch volumio[1176]: info: Starting Streaming Service Transparent Proxy May 29 20:32:24 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 20:32:24 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 20:32:24 rasptouch volumio[1176]: info: Streaming services startup May 29 20:32:24 rasptouch volumio[1176]: info: Starting Streaming Daemon May 29 20:32:24 rasptouch sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 20:32:24 rasptouch sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:24 rasptouch sudo[1599]: pam_unix(sudo:session): session closed for user root May 29 20:32:24 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 20:32:24 rasptouch volumio[1176]: error: Cannot start Volumio Streaming Daemon May 29 20:32:24 rasptouch volumio[1176]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 20:32:24 rasptouch volumio[1176]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 20:32:24 rasptouch volumio[1176]: STREAMING PROXY: Starting server on port 3245 May 29 20:32:24 rasptouch volumio[1176]: Node JS runtime: 14 May 29 20:32:27 rasptouch volumio[1176]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 20:32:30 rasptouch volumio[1176]: info: MyVolumio token set successfully May 29 20:32:30 rasptouch volumio[1176]: info: MYVOLUMIO: Adding device May 29 20:32:30 rasptouch volumio[1176]: info: MYVOLUMIO: Evaluating Server May 29 20:32:31 rasptouch volumio[1176]: info: MyVolumio Plan changed: premium May 29 20:32:31 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 29 20:32:31 rasptouch volumio[1176]: info: Removing browser output: myVolumio user plan is not superstar May 29 20:32:31 rasptouch volumio[1176]: info: Removing audio output: May 29 20:32:31 rasptouch volumio[1176]: info: MYVOLUMIO: Adding device May 29 20:32:31 rasptouch volumio[1176]: info: MYVOLUMIO: Evaluating Server May 29 20:32:33 rasptouch volumio[1176]: info: Setting Geolocation for MyVolumio to eu6 May 29 20:32:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:33 rasptouch volumio[1176]: info: Setting Geolocation for MyVolumio to eu10 May 29 20:32:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:33 rasptouch volumio[1176]: info: Remote config written successfully May 29 20:32:33 rasptouch volumio[1176]: info: Starting Tunnel 1 May 29 20:32:33 rasptouch volumio[1176]: info: Starting Tunnel Connection Checker May 29 20:32:33 rasptouch volumio[1176]: info: Completed starting MyVolumio Plugin May 29 20:32:35 rasptouch volumio[1176]: info: MYVolumio Device enabled May 29 20:32:35 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 29 20:32:35 rasptouch volumio[1176]: info: MyVolumio status changed May 29 20:32:35 rasptouch volumio[1176]: info: Streaming services startup May 29 20:32:35 rasptouch volumio[1176]: info: Starting Streaming Daemon May 29 20:32:35 rasptouch sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 20:32:35 rasptouch volumio[1176]: info: Updating MyVolumio device info May 29 20:32:35 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:35 rasptouch sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:35 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:35 rasptouch volumio[1176]: info: Updating MyVolumio device info May 29 20:32:35 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:35 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:35 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:35 rasptouch sudo[1696]: pam_unix(sudo:session): session closed for user root May 29 20:32:36 rasptouch ntpd[818]: Soliciting pool server 162.159.200.123 May 29 20:32:37 rasptouch ntpd[818]: Soliciting pool server 91.182.44.189 May 29 20:32:38 rasptouch ntpd[818]: Soliciting pool server 193.190.253.214 May 29 20:32:39 rasptouch volumio[1176]: error: Cannot start Volumio Streaming Daemon May 29 20:32:39 rasptouch volumio[1176]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 20:32:39 rasptouch volumio[1176]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 20:32:39 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 20:32:39 rasptouch ntpd[818]: Soliciting pool server 185.153.43.4 May 29 20:32:40 rasptouch ntpd[818]: Soliciting pool server 45.87.76.3 May 29 20:32:41 rasptouch ntpd[818]: Soliciting pool server 81.82.227.219 May 29 20:32:41 rasptouch ntpd[818]: Soliciting pool server 162.159.200.1 May 29 20:32:41 rasptouch ntpd[818]: Soliciting pool server 195.13.1.153 May 29 20:32:42 rasptouch ntpd[818]: Soliciting pool server 185.21.134.11 May 29 20:32:42 rasptouch ntpd[818]: Soliciting pool server 185.89.20.5 May 29 20:32:43 rasptouch ntpd[818]: Soliciting pool server 91.182.44.189 May 29 20:32:43 rasptouch ntpd[818]: Soliciting pool server 45.87.77.15 May 29 20:32:43 rasptouch volumio[1176]: Cannot compose Albumart path May 29 20:32:44 rasptouch volumio[1176]: Cannot compose Albumart path May 29 20:32:44 rasptouch ntpd[818]: Soliciting pool server 2a02:578:4c12::1f4b May 29 20:32:45 rasptouch volumio[1176]: info: Successfully Added MyVolumio device May 29 20:32:45 rasptouch volumio[1176]: info: Successfully Added MyVolumio device May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetBrowseSources May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:32:45 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 29 20:32:45 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 29 20:32:45 rasptouch volumio[1176]: info: [1748543565855] Starting BluetoothController May 29 20:32:45 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 29 20:32:47 rasptouch ntpd[818]: receive: Unexpected origin timestamp 0xebe326ce.765f5d08 does not match aorg 0000000000.00000000 from server@195.13.1.153 xmt 0xebe326cf.5dff1978 May 29 20:32:47 rasptouch ntpd[818]: receive: Unexpected origin timestamp 0xebe326ce.7666b29c does not match aorg 0000000000.00000000 from server@185.153.43.4 xmt 0xebe326cf.5d9ee562 May 29 20:32:47 rasptouch ntpd[818]: receive: Unexpected origin timestamp 0xebe326ce.7665671c does not match aorg 0000000000.00000000 from server@81.82.227.219 xmt 0xebe326cf.5f451c9b May 29 20:32:47 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 29 20:32:47 rasptouch sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 29 20:32:47 rasptouch sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:47 rasptouch sudo[1715]: pam_unix(sudo:session): session closed for user root May 29 20:32:47 rasptouch volumio-remote-updater[661]: [2025-05-29 20:32:47] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 29 20:32:47 rasptouch volumio[1176]: info: MRS: MultiRoom plugin initialized May 29 20:32:47 rasptouch volumio[1176]: info: MRS: STOPPING SNAPCLIENT May 29 20:32:47 rasptouch volumio[1176]: info: MRS: Snap server stop May 29 20:32:47 rasptouch sudo[1731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 29 20:32:47 rasptouch sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch volumio[1176]: info: MRS: STOPPING volumioStreaming May 29 20:32:48 rasptouch sudo[1731]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch sudo[1734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 29 20:32:48 rasptouch sudo[1734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch sudo[1734]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 29 20:32:48 rasptouch sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 29 20:32:48 rasptouch sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 29 20:32:48 rasptouch sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch sudo[1737]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch sudo[1740]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 29 20:32:48 rasptouch volumio[1176]: info: Preparing to generate the ALSA configuration file May 29 20:32:48 rasptouch volumio[1176]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 29 20:32:48 rasptouch volumio[1176]: info: Reading ALSA contributions from plugins. May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 20:32:48 rasptouch volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 29 20:32:48 rasptouch volumio[1176]: info: MRS: Removed streaming files May 29 20:32:48 rasptouch volumio[1176]: info: MRS: volumioStreaming STOPPED May 29 20:32:48 rasptouch volumio[1176]: info: MRS: SNAPSERVER STOPPED May 29 20:32:48 rasptouch volumio[1176]: info: MRS: SNAPCLIENT STOPPED May 29 20:32:48 rasptouch sudo[1747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 29 20:32:48 rasptouch sudo[1747]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:48 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:48 rasptouch volumio[1176]: info: Asound.conf file written May 29 20:32:48 rasptouch systemd[1]: Started MyVolumio SSH Tunnel. May 29 20:32:48 rasptouch sudo[1747]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch autossh[1750]: port set to 0, monitoring disabled May 29 20:32:48 rasptouch autossh[1750]: starting ssh (count 1) May 29 20:32:48 rasptouch autossh[1750]: ssh child pid is 1755 May 29 20:32:48 rasptouch sudo[1754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 29 20:32:48 rasptouch sudo[1754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch sudo[1754]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch volumio[1176]: info: Output device has changed, restarting MPD May 29 20:32:48 rasptouch sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 20:32:48 rasptouch sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch sudo[1760]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch volumio[1176]: info: Output device has changed, restarting Shairport Sync May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:48 rasptouch sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 20:32:48 rasptouch sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch volumiossh-tunnel[1749]: Warning: Permanently added '[eu10.myvolumio.org]:2222,[46.101.141.203]:2222' (RSA) to the list of known hosts. May 29 20:32:48 rasptouch systemd[1]: Stopping Music Player Daemon... May 29 20:32:48 rasptouch volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 20:32:48 rasptouch volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 29 20:32:48 rasptouch volumio[1176]: info: MRS: Pushing multiroomSync output for this device May 29 20:32:48 rasptouch volumio[1176]: info: MRS: Pushing multiroomSync output May 29 20:32:48 rasptouch systemd[1]: mpd.service: Succeeded. May 29 20:32:48 rasptouch systemd[1]: Stopped Music Player Daemon. May 29 20:32:48 rasptouch volumio[1176]: info: Adding audio output: May 29 20:32:48 rasptouch volumio[1176]: info: Adding audio output: May 29 20:32:48 rasptouch sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rasptouch May 29 20:32:48 rasptouch sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 29 20:32:48 rasptouch sudo[1771]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch systemd[1]: Starting Music Player Daemon... May 29 20:32:48 rasptouch volumio[1176]: info: Adding METAVOLUMIO REST API Endpoints May 29 20:32:48 rasptouch volumio[1176]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 29 20:32:48 rasptouch volumio[1176]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 29 20:32:48 rasptouch volumio[1176]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 29 20:32:48 rasptouch volumio[1176]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 29 20:32:48 rasptouch volumio[1176]: info: Preparing CD Folders May 29 20:32:48 rasptouch volumio[1176]: info: Adding CD REST API Endpoints May 29 20:32:48 rasptouch volumio[1176]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 29 20:32:48 rasptouch volumio[1176]: info: Starting UDEV Watcher for CD May 29 20:32:48 rasptouch volumio[1176]: info: Detecting CD presence with UDEV May 29 20:32:48 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 29 20:32:48 rasptouch volumio[1176]: info: Adding inputs REST Endpoints May 29 20:32:48 rasptouch volumio[1176]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 29 20:32:48 rasptouch volumio[1176]: info: Scanning Audio Inputs May 29 20:32:48 rasptouch sudo[1772]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 20:32:48 rasptouch sudo[1772]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:48 rasptouch volumio[1176]: info: Checking against Known Cards name May 29 20:32:48 rasptouch volumio[1176]: info: Adding Server instance for streaming May 29 20:32:48 rasptouch sudo[1772]: pam_unix(sudo:session): session closed for user root May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 29 20:32:48 rasptouch volumio[1176]: error: Hi Res Audio Failed Login: Missing Login Data May 29 20:32:48 rasptouch volumio[1176]: info: Adding HIGHRESAUDIO REST API Endpoints May 29 20:32:48 rasptouch volumio[1176]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 29 20:32:48 rasptouch volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 29 20:32:48 rasptouch volumio[1176]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 29 20:32:49 rasptouch sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 29 20:32:49 rasptouch sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:49 rasptouch volumio[1176]: info: QobuzConnect: Starting Qobuz Connect socket and service May 29 20:32:49 rasptouch sudo[1782]: pam_unix(sudo:session): session closed for user root May 29 20:32:49 rasptouch volumio[1176]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 29 20:32:49 rasptouch sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 29 20:32:49 rasptouch volumio[1176]: info: Stopping AccessToken refresher cron May 29 20:32:49 rasptouch sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:49 rasptouch sudo[1789]: pam_unix(sudo:session): session closed for user root May 29 20:32:49 rasptouch volumio[1176]: info: AccessToken refresher cron started May 29 20:32:49 rasptouch volumio[1176]: info: Stopping AccessToken refresher cron for QOBUZ May 29 20:32:49 rasptouch sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 29 20:32:49 rasptouch sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:49 rasptouch volumio[1176]: info: AccessToken refresher cron started for QOBUZ May 29 20:32:49 rasptouch volumio[1176]: info: Adding TIDAL REST API Endpoints May 29 20:32:49 rasptouch volumio[1176]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 29 20:32:49 rasptouch volumio[1176]: info: Adding QOBUZ REST API Endpoints May 29 20:32:49 rasptouch volumio[1176]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 29 20:32:49 rasptouch volumio[1176]: info: Successfully Updated MyVolumio device May 29 20:32:49 rasptouch systemd[1]: Started Volumio Qobuz Connect Service. May 29 20:32:49 rasptouch volumio[1176]: info: Successfully Updated MyVolumio device May 29 20:32:49 rasptouch sudo[1791]: pam_unix(sudo:session): session closed for user root May 29 20:32:49 rasptouch volumio[1176]: info: Bluetooth name changed to Rasptouch May 29 20:32:49 rasptouch volumio[1176]: info: MPD Permissions set May 29 20:32:49 rasptouch volumio[1176]: info: Remote SSH Started May 29 20:32:49 rasptouch volumio[1176]: info: Executing endpoint qc_getconfig May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.407 [1793.1793] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:49 rasptouch volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 29 20:32:49 rasptouch volumio[1176]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 29 20:32:49 rasptouch volumio[1176]: info: QobuzConnect: QOBUZ Connect daemon connected May 29 20:32:49 rasptouch volumio[1176]: info: Starting Shairport Sync May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.514 [1793.1793] INFO VolumeManager: [0x2097ad8]: Setting new playback volume: 75 May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.514 [1793.1793] INFO VolumeManager: [0x2097ad8]: Setting new mute state: 0 May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.515 [1793.1793] INFO QobuzConnect: [0x20984a8]: Client initialized! May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.515 [1793.1793] INFO SampleApp: Starting Avahi advertising, name: Rasptouch, service name: _qobuz-connect._tcp May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.523 [1793.1793] INFO LocalConfigManager: [0x20973b8]: Starting Local Configuration server May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.523 [1793.1793] INFO SampleApp: Starting Local configuration server May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.523 [1793.1793] INFO SampleApp: Connected to UNIX socket client 0x208cf60 May 29 20:32:49 rasptouch qobuz-connect[1793]: 20250529 20:32:49.556 [1793.1793] INFO SampleApp: Playback volume changed: 75 May 29 20:32:49 rasptouch sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 20:32:49 rasptouch sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:49 rasptouch systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 20:32:49 rasptouch systemd[1]: shairport-sync.service: Succeeded. May 29 20:32:49 rasptouch systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:49 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:49 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:49 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:49 rasptouch systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 20:32:49 rasptouch sudo[1801]: pam_unix(sudo:session): session closed for user root May 29 20:32:49 rasptouch volumio[1176]: info: Shairport-Sync Started May 29 20:32:49 rasptouch mpd[1780]: May 29 20:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 20:32:50 rasptouch systemd[1]: Started Music Player Daemon. May 29 20:32:50 rasptouch sudo[1763]: pam_unix(sudo:session): session closed for user root May 29 20:32:50 rasptouch volumio[1176]: error: updateQueue error: null May 29 20:32:51 rasptouch sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 29 20:32:51 rasptouch sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:51 rasptouch systemd[1]: Started Volumio Bluetooth Module. May 29 20:32:51 rasptouch sudo[1809]: pam_unix(sudo:session): session closed for user root May 29 20:32:51 rasptouch volumio[1176]: info: Volumio BT Module successfully started May 29 20:32:51 rasptouch sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 29 20:32:51 rasptouch sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:51 rasptouch volumiobt[1811]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 29 20:32:51 rasptouch sudo[1812]: pam_unix(sudo:session): session closed for user root May 29 20:32:52 rasptouch volumio[1176]: info: TidalConnect service stoped! May 29 20:32:52 rasptouch volumio[1176]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 29 20:32:52 rasptouch volumio[1176]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 29 20:32:52 rasptouch sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 29 20:32:52 rasptouch sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:52 rasptouch systemd[1]: Started Volumio Tidal Connect Service. May 29 20:32:52 rasptouch sudo[1824]: pam_unix(sudo:session): session closed for user root May 29 20:32:52 rasptouch volumio[1176]: info: Executing endpoint tc_getconfig May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 29 20:32:52 rasptouch vtcs[1826]: STARTING TidalConnect services, version: 1.4.0.34 May 29 20:32:52 rasptouch vtcs[1826]: STARTED TidalConnect services. May 29 20:32:52 rasptouch volumio[1176]: info: Executing endpoint tc_connect May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 29 20:32:52 rasptouch volumio[1176]: info: Connecting to TidalConnect May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::servicePushState May 29 20:32:52 rasptouch volumio[1176]: info: CoreStateMachine::pushState May 29 20:32:52 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::volumioPushState May 29 20:32:52 rasptouch volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 29 20:32:52 rasptouch volumio[1176]: info: MRS: Pushing multiroomSync output May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:52 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:52 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:52 rasptouch volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::servicePushState May 29 20:32:52 rasptouch volumio[1176]: info: CoreStateMachine::pushState May 29 20:32:52 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::volumioPushState May 29 20:32:52 rasptouch volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 29 20:32:52 rasptouch volumio[1176]: info: MRS: Pushing multiroomSync output May 29 20:32:52 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:32:52 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:52 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:32:52 rasptouch volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 29 20:32:52 rasptouch volumio-remote-updater[661]: [2025-05-29 20:32:52] [connect] Successful connection May 29 20:32:52 rasptouch volumio-remote-updater[661]: [2025-05-29 20:32:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748543572 101 May 29 20:32:52 rasptouch volumio[1176]: 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: 5 May 29 20:32:52 rasptouch sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 29 20:32:52 rasptouch sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:32:52 rasptouch volumiobt[1811]: pulseaudio: no process found May 29 20:32:52 rasptouch sudo[1859]: pam_unix(sudo:session): session closed for user root May 29 20:32:53 rasptouch dbus-daemon[663]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=1863 comm="/usr/bin/pulseaudio --high-priority=true ") May 29 20:32:53 rasptouch systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 29 20:32:53 rasptouch volumio[1176]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:32:53 rasptouch dbus-daemon[663]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 29 20:32:53 rasptouch systemd[1]: Started RealtimeKit Scheduling Policy Service. May 29 20:32:53 rasptouch rtkit-daemon[1864]: Successfully called chroot. May 29 20:32:53 rasptouch rtkit-daemon[1864]: Successfully dropped privileges. May 29 20:32:53 rasptouch rtkit-daemon[1864]: Successfully limited resources. May 29 20:32:53 rasptouch rtkit-daemon[1864]: Running. May 29 20:32:53 rasptouch rtkit-daemon[1864]: Watchdog thread running. May 29 20:32:53 rasptouch rtkit-daemon[1864]: Canary thread running. May 29 20:32:53 rasptouch dbus-daemon[663]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=1864 comm="/usr/lib/rtkit/rtkit-daemon ") May 29 20:32:53 rasptouch systemd[1]: Starting Authorization Manager... May 29 20:32:53 rasptouch volumio[1176]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:32:53 rasptouch polkitd[1868]: started daemon version 0.105 using authority implementation `local' version `0.105' May 29 20:32:53 rasptouch dbus-daemon[663]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 29 20:32:53 rasptouch systemd[1]: Started Authorization Manager. May 29 20:32:53 rasptouch pulseaudio[1863]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 29 20:32:53 rasptouch pulseaudio[1863]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 29 20:32:53 rasptouch volumiobt[1811]: Applying permissions May 29 20:32:53 rasptouch volumiobt[1811]: Setting BT discoverable and pairable May 29 20:32:53 rasptouch pulseaudio[1863]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 29 20:32:53 rasptouch bluetoothd[802]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource May 29 20:32:53 rasptouch bluetoothd[802]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink May 29 20:32:53 rasptouch kernel: Bluetooth: RFCOMM TTY layer initialized May 29 20:32:53 rasptouch kernel: Bluetooth: RFCOMM socket layer initialized May 29 20:32:53 rasptouch kernel: Bluetooth: RFCOMM ver 1.11 May 29 20:32:53 rasptouch volumiobt[1811]: [CHG] Controller 2C:CF:67:07:C6:31 Class: 0x000c041c May 29 20:32:53 rasptouch volumiobt[1811]: [55B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [117B blob data] May 29 20:32:53 rasptouch volumiobt[1811]: [bluetooth]# -e power on May 29 20:32:53 rasptouch volumiobt[1811]: Invalid command in menu main: -e May 29 20:32:53 rasptouch volumiobt[1811]: May 29 20:32:53 rasptouch volumiobt[1811]: Use "help" for a list of available commands in a menu. May 29 20:32:53 rasptouch volumiobt[1811]: Use "menu " if you want to enter any submenu. May 29 20:32:53 rasptouch volumiobt[1811]: Use "back" if you want to return to menu main. May 29 20:32:53 rasptouch volumiobt[1811]: [bluetooth]# agent on May 29 20:32:53 rasptouch volumiobt[1811]: Agent is already registered May 29 20:32:53 rasptouch volumiobt[1811]: [bluetooth]# discoverable on May 29 20:32:53 rasptouch volumiobt[1811]: [bluetooth]# pairable on May 29 20:32:53 rasptouch volumiobt[1811]: [bluetooth]# agent NoInputNoOutput May 29 20:32:53 rasptouch volumiobt[1811]: Agent is already registered May 29 20:32:53 rasptouch volumiobt[1811]: [bluetooth]# default-agent May 29 20:32:53 rasptouch volumiobt[1811]: [bluetooth]# quit May 29 20:32:53 rasptouch volumiobt[1811]: [59B blob data] May 29 20:32:53 rasptouch volumio[1176]: info: MRS: Getting audio outputs on start May 29 20:32:53 rasptouch volumio[1176]: info: MRS: Requesting all other devices output May 29 20:32:55 rasptouch volumio[1176]: info: TidalConnect service started! May 29 20:32:55 rasptouch volumio[1176]: [Metrics] CommandRouter: 55s 154.10ms May 29 20:32:55 rasptouch volumio[1176]: info: CoreCommandRouter::volumiosetStartupVolume May 29 20:32:55 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:32:55 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:32:55 rasptouch volumio[1176]: info: CoreCommandRouter::Close All Modals sent May 29 20:32:55 rasptouch volumio[1176]: info: CoreCommandRouter::Close All Modals sent May 29 20:32:56 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 29 20:32:56 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 20:32:56 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 29 20:33:02 rasptouch volumio[1176]: info: BOOT COMPLETED May 29 20:33:18 rasptouch sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:33:18 rasptouch sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:18 rasptouch sudo[1915]: pam_unix(sudo:session): session closed for user root May 29 20:33:18 rasptouch sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 20:33:18 rasptouch sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:18 rasptouch sudo[1918]: pam_unix(sudo:session): session closed for user root May 29 20:33:18 rasptouch volumio[1176]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:18 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 20:33:18 rasptouch volumio[1176]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 29 20:33:18 rasptouch volumio[1176]: info: Received Get System Info May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:33:18 rasptouch volumio[1176]: info: Discovery: Getting this device information May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:18 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:18 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:18 rasptouch volumio[1176]: info: Listing playlists May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 20:33:18 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 29 20:33:19 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:19 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:33:20 rasptouch volumio[1176]: info: Received Get System Info May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:33:20 rasptouch volumio[1176]: info: Discovery: Getting this device information May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:20 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:33:20 rasptouch volumio[1176]: info: Received Get System Info May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:33:20 rasptouch volumio[1176]: info: Discovery: Getting this device information May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:20 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:20 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:33:21 rasptouch volumio[1176]: info: Executing endpoint metavolumio May 29 20:33:21 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:33:21 rasptouch volumio[1176]: info: Executing endpoint metavolumio May 29 20:33:21 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:33:21 rasptouch volumio[1176]: info: Executing endpoint metavolumio May 29 20:33:21 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:33:22 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:33:22 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 20:33:26 rasptouch volumio[1176]: info: Enabling plugin touch_display May 29 20:33:26 rasptouch volumio[1176]: info: Loading plugin "touch_display"... May 29 20:33:26 rasptouch volumio[1176]: info: Applying required configuration parameters for plugin touch_display May 29 20:33:26 rasptouch volumio[1176]: info: PLUGIN START: touch_display May 29 20:33:26 rasptouch volumio[1176]: info: Loading i18n strings for locale en May 29 20:33:26 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:33:26 rasptouch sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 29 20:33:26 rasptouch sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:26 rasptouch sudo[1938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 29 20:33:26 rasptouch sudo[1938]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:26 rasptouch volumio[1176]: info: touch_display: Backlight interface detected. May 29 20:33:26 rasptouch sudo[1935]: pam_unix(sudo:session): session closed for user root May 29 20:33:26 rasptouch sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 29 20:33:26 rasptouch sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:26 rasptouch systemd[1]: Reloading. May 29 20:33:26 rasptouch volumio[1176]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 29 20:33:26 rasptouch volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 29 20:33:26 rasptouch volumio[1176]: info: touch_display: Raspberry Pi Foundation touch screen detected. May 29 20:33:26 rasptouch sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 29 20:33:26 rasptouch sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:26 rasptouch sudo[1957]: pam_unix(sudo:session): session closed for user root May 29 20:33:26 rasptouch sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness May 29 20:33:26 rasptouch sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:26 rasptouch volumio[1176]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 29 20:33:26 rasptouch sudo[1960]: pam_unix(sudo:session): session closed for user root May 29 20:33:26 rasptouch volumio[1176]: info: touch_display: File permissions for backlight brightness control set. May 29 20:33:26 rasptouch 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. May 29 20:33:27 rasptouch sudo[1938]: pam_unix(sudo:session): session closed for user root May 29 20:33:27 rasptouch systemd[1]: Reloading. May 29 20:33:27 rasptouch volumio[1176]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 29 20:33:27 rasptouch 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. May 29 20:33:27 rasptouch sudo[1941]: pam_unix(sudo:session): session closed for user root May 29 20:33:27 rasptouch volumio[1176]: info: touch_display: systemctl daemon-reload succeeded. May 29 20:33:27 rasptouch sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 29 20:33:27 rasptouch sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:27 rasptouch systemd[1]: Started Volumio Kiosk. May 29 20:33:27 rasptouch sudo[1978]: pam_unix(sudo:session): session closed for user root May 29 20:33:27 rasptouch volumio[1176]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 29 20:33:27 rasptouch volumio[1176]: info: touch_display: Volumio Kiosk started. May 29 20:33:27 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:27 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:27 rasptouch volumio[1176]: info: Done. May 29 20:33:27 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:27 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:27 rasptouch volumio[1176]: info: touch_display: X display number found: May 29 20:33:27 rasptouch startx[1980]: X.Org X Server 1.20.4 May 29 20:33:27 rasptouch startx[1980]: X Protocol Version 11, Revision 0 May 29 20:33:27 rasptouch startx[1980]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian May 29 20:33:27 rasptouch startx[1980]: Current Operating System: Linux rasptouch 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l May 29 20:33:27 rasptouch startx[1980]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=2C:CF:67:07:C6:2F 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=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def 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 May 29 20:33:27 rasptouch startx[1980]: Build Date: 04 April 2023 07:50:56AM May 29 20:33:27 rasptouch startx[1980]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) May 29 20:33:27 rasptouch startx[1980]: Current version of pixman: 0.36.0 May 29 20:33:27 rasptouch startx[1980]: Before reporting problems, check http://wiki.x.org May 29 20:33:27 rasptouch startx[1980]: to make sure that you have the latest version. May 29 20:33:27 rasptouch startx[1980]: Markers: (--) probed, (**) from config file, (==) default setting, May 29 20:33:27 rasptouch startx[1980]: (++) from command line, (!!) notice, (II) informational, May 29 20:33:27 rasptouch startx[1980]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 29 20:33:27 rasptouch startx[1980]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu May 29 20:33:27 2025 May 29 20:33:27 rasptouch startx[1980]: (==) Using config directory: "/etc/X11/xorg.conf.d" May 29 20:33:27 rasptouch startx[1980]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 29 20:33:27 rasptouch volumio[1176]: info: touch_display: X display number found: 0 May 29 20:33:28 rasptouch volumio[1176]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 29 20:33:28 rasptouch volumio[1176]: info: touch_display: X display number found: 0 May 29 20:33:28 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 29 20:33:29 rasptouch volumio[1176]: info: touch_display: Setting screensaver timeout to 120 seconds. May 29 20:33:31 rasptouch volumio[1176]: info: CoreCommandRouter::volumioGetState May 29 20:33:31 rasptouch volumio[1176]: info: CorePlayQueue::getTrack 0 May 29 20:33:33 rasptouch volumio[1176]: info: Executing endpoint metavolumio May 29 20:33:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:33:33 rasptouch volumio[1176]: info: Executing endpoint metavolumio May 29 20:33:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:33:33 rasptouch volumio[1176]: info: Executing endpoint metavolumio May 29 20:33:33 rasptouch volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:33:36 rasptouch volumio[1176]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 29 20:33:36 rasptouch volumio[1176]: info: PLUGIN onReboot : networkfs May 29 20:33:36 rasptouch sudo[2171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Alphabet May 29 20:33:36 rasptouch sudo[2171]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:33:36 rasptouch systemd[1]: mnt-NAS-Alphabet.mount: Succeeded. May 29 20:33:36 rasptouch sudo[2171]: pam_unix(sudo:session): session closed for user root May 29 20:33:36 rasptouch volumio[1176]: info: PLUGIN onReboot : audiophonicsonoff May 29 20:33:36 rasptouch volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:33:36 rasptouch volumio[1176]: TypeError: Cannot read property 'writeSync' of undefined May 29 20:33:36 rasptouch volumio[1176]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) May 29 20:33:36 rasptouch volumio[1176]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 29 20:33:36 rasptouch volumio[1176]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 29 20:33:36 rasptouch volumio[1176]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 29 20:33:36 rasptouch volumio[1176]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 29 20:33:36 rasptouch volumio[1176]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 29 20:33:36 rasptouch volumio[1176]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) May 29 20:33:36 rasptouch volumio[1176]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) May 29 20:33:36 rasptouch volumio[1176]: at Socket.emit (events.js:315:20) May 29 20:33:36 rasptouch volumio[1176]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 29 20:33:36 rasptouch volumio[1176]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 29 20:33:36 rasptouch volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:33:36 rasptouch sudo[2193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 20:32 May 29 20:33:36 rasptouch sudo[2193]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"