-- Logs begin at Sat 2025-04-26 21:22:42 AST, end at Sat 2025-04-26 21:53:57 AST. -- Apr 26 21:52:00 volumio volumio[21734]: info: Mounting Device EFI Apr 26 21:52:00 volumio sudo[21822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 26 21:52:00 volumio sudo[21822]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:00 volumio sudo[21822]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:00 volumio volumio[21734]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Apr 26 21:52:00 volumio volumio[21734]: error: Failed to mount EFI: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/EFI" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 26 21:52:00 volumio volumio[21734]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Apr 26 21:52:00 volumio volumio[21734]: info: Mounting Device Music Apr 26 21:52:00 volumio sudo[21829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/Music -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 26 21:52:00 volumio sudo[21829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:00 volumio ntfs-3g[21835]: Version 2017.3.23AR.3 integrated FUSE 28 Apr 26 21:52:00 volumio ntfs-3g[21835]: Mounted /dev/sda2 (Read-Write, label "Music", NTFS 3.1) Apr 26 21:52:00 volumio ntfs-3g[21835]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 26 21:52:00 volumio ntfs-3g[21835]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Apr 26 21:52:00 volumio ntfs-3g[21835]: Global ownership and permissions enforced, configuration type 7 Apr 26 21:52:00 volumio sudo[21829]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:00 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "volumio_command_line_client"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "upnp"... Apr 26 21:52:00 volumio volumio[21734]: info: [1745718720190] Starting Upmpd Daemon Apr 26 21:52:00 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "my_music"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "mpd"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "upnp_browser"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "alarm-clock"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "airplay_emulation"... Apr 26 21:52:00 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "last_100"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "webradio"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "i2s_dacs"... Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "volumiodiscovery"... Apr 26 21:52:00 volumio volumio[21734]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 21:52:00 volumio volumio[21734]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 21:52:00 volumio volumio[21734]: *** WARNING *** For more information see Apr 26 21:52:00 volumio node[21734]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 21:52:00 volumio volumio[21734]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 21:52:00 volumio volumio[21734]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 21:52:00 volumio volumio[21734]: *** WARNING *** For more information see Apr 26 21:52:00 volumio node[21734]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 21:52:00 volumio node[21734]: *** WARNING *** For more information see Apr 26 21:52:00 volumio node[21734]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 21:52:00 volumio node[21734]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 21:52:00 volumio node[21734]: *** WARNING *** For more information see Apr 26 21:52:00 volumio volumio[21734]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 21:52:00 volumio volumio[21734]: info: Discovery: Started advertising with name: Volumio Apr 26 21:52:00 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 21:52:00 volumio volumio[21734]: info: Loading plugin "calmradio"... Apr 26 21:52:01 volumio volumio[21734]: info: Loading plugin "youtube2"... Apr 26 21:52:02 volumio volumio-remote-updater[644]: [2025-04-26 21:52:02] [connect] Successful connection Apr 26 21:52:03 volumio volumio[21734]: info: Loading plugin "ytmusic"... Apr 26 21:52:03 volumio sudo[21805]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:04 volumio volumio[21734]: info: Loading plugin "autostart"... Apr 26 21:52:04 volumio volumio[21734]: info: Applying required configuration parameters for plugin autostart Apr 26 21:52:04 volumio volumio[21734]: info: AutoStart - onVolumioStart - read config.json Apr 26 21:52:04 volumio volumio[21734]: info: Loading plugin "now_playing"... Apr 26 21:52:05 volumio volumio[21734]: info: Loading plugin "outputs"... Apr 26 21:52:05 volumio volumio[21734]: info: Loading plugin "albumart"... Apr 26 21:52:05 volumio volumio[21734]: info: Plugin example_plugin is not enabled Apr 26 21:52:05 volumio volumio[21734]: info: Loading plugin "inputs"... Apr 26 21:52:05 volumio volumio[21734]: info: Loading plugin "updater_comm"... Apr 26 21:52:05 volumio volumio[21734]: info: Plugin mpdemulation is not enabled Apr 26 21:52:05 volumio volumio[21734]: info: Loading plugin "rest_api"... Apr 26 21:52:05 volumio volumio[21734]: info: Loading plugin "websocket"... Apr 26 21:52:05 volumio volumio[21734]: info: Starting Socket.io Server version 2.3.0 Apr 26 21:52:05 volumio volumio[21734]: info: Loading plugin "80s80s"... Apr 26 21:52:05 volumio volumio[21734]: Forking 3 albumart workers Apr 26 21:52:06 volumio volumio[21734]: info: Applying required configuration parameters for plugin 80s80s Apr 26 21:52:06 volumio volumio[21734]: info: [1745718726094] [80s80s] API delay: 30 Apr 26 21:52:06 volumio volumio[21734]: info: Loading plugin "minidlna"... Apr 26 21:52:06 volumio volumio[21734]: info: Applying required configuration parameters for plugin minidlna Apr 26 21:52:06 volumio volumio[21734]: info: Loading plugin "radio_paradise"... Apr 26 21:52:06 volumio volumio[21734]: info: Applying required configuration parameters for plugin radio_paradise Apr 26 21:52:06 volumio volumio[21734]: info: [1745718726417] [RadioParadise] API delay: 5 Apr 26 21:52:06 volumio volumio[21734]: info: Loading plugin "audiophonics_evo_sabre"... Apr 26 21:52:06 volumio volumio[21734]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Apr 26 21:52:06 volumio volumio[21734]: info: Loading plugin "audiophonicsonoff"... Apr 26 21:52:06 volumio volumio[21734]: Starting albumart workers Apr 26 21:52:06 volumio volumio[21734]: Starting albumart workers Apr 26 21:52:06 volumio volumio[21734]: Starting albumart workers Apr 26 21:52:06 volumio volumio[21734]: info: Applying required configuration parameters for plugin audiophonicsonoff Apr 26 21:52:06 volumio volumio[21734]: info: Audiophonics on/off initiated Apr 26 21:52:06 volumio volumio[21734]: info: Loading plugin "Systeminfo"... Apr 26 21:52:06 volumio volumio[21734]: info: Loading plugin "touch_display"... Apr 26 21:52:07 volumio volumio[21734]: info: Applying required configuration parameters for plugin touch_display Apr 26 21:52:07 volumio volumio[21734]: info: Loading i18n strings for locale fr Apr 26 21:52:07 volumio volumio[21734]: Updating browse sources language Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::initPlayerControls Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: Express server listening on port 3000 Apr 26 21:52:07 volumio volumio[21734]: [Metrics] WebUI: 8s 963.86ms Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::resetVolumioState Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::getcurrentVolume Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 21:52:07 volumio volumio[21734]: info: Volumio Network Manager: Network status updated: 2 Apr 26 21:52:07 volumio volumio[21734]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 26 21:52:07 volumio volumio[21734]: verbose: New Socket.io Connection to 192.168.2.146 from 192.168.2.113 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Apr 26 21:52:07 volumio volumio[21734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 26 21:52:07 volumio volumio[21734]: verbose: New Socket.io Connection to 192.168.2.146 from 192.168.2.113 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Apr 26 21:52:07 volumio volumio[21734]: verbose: New Socket.io Connection to 192.168.2.146 from 192.168.2.113 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Apr 26 21:52:07 volumio volumio[21734]: info: VolumeController:: Volume=undefined Mute =false Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:52:07 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::updateTrackBlock Apr 26 21:52:07 volumio volumio[21734]: info: CorePlayQueue::getTrackBlock Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 21:52:07 volumio volumio[21734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::setRepeat null single undefined Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:52:07 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::setRandom false Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:52:07 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:52:07 volumio volumio[21734]: info: Setting Device type: Raspberry PI Apr 26 21:52:07 volumio volumio[21734]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 21:52:07 volumio volumio[21734]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 21:52:07 volumio volumio[21734]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 21:52:07 volumio volumio[21734]: info: Reloading queue from file Apr 26 21:52:07 volumio volumio[21734]: info: Completed loading Core Plugins Apr 26 21:52:07 volumio volumio[21734]: info: Preparing to generate the ALSA configuration file Apr 26 21:52:07 volumio volumio[21734]: info: VolumeController:: Volume=undefined Mute =false Apr 26 21:52:07 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:52:07 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:52:07 volumio volumio[21734]: info: Asound.conf file written Apr 26 21:52:07 volumio sudo[22167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 26 21:52:07 volumio sudo[22167]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:07 volumio sudo[22167]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:07 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Apr 26 21:52:07 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Apr 26 21:52:07 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Apr 26 21:52:07 volumio volumio[21734]: info: Output device has changed, restarting MPD Apr 26 21:52:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 26 21:52:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:52:07 volumio sudo[22172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 21:52:07 volumio sudo[22172]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:07 volumio volumio[21734]: info: Output device has changed, restarting Shairport Sync Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:07 volumio sudo[22172]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:07 volumio sudo[22175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 21:52:07 volumio sudo[22175]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:07 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 21:52:07 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:52:07 volumio volumio[21734]: info: ___________ START PLUGINS ___________ Apr 26 21:52:07 volumio volumio[21734]: info: ControllerMpd::onStart: Initializing MPD Apr 26 21:52:07 volumio volumio[21734]: info: Creating MPD Configuration file Apr 26 21:52:07 volumio systemd[1]: mpd.service: Succeeded. Apr 26 21:52:07 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 21:52:07 volumio sudo[22182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 21:52:07 volumio sudo[22182]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:07 volumio volumio[21734]: info: [1745718727958] CoreMusicLibrary::Adding element Serveurs Média Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:07 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:07 volumio systemd[1]: Starting Music Player Daemon... Apr 26 21:52:07 volumio sudo[22185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 21:52:07 volumio sudo[22185]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:07 volumio sudo[22182]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 26 21:52:08 volumio systemd[1]: mpd.service: Succeeded. Apr 26 21:52:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 21:52:08 volumio systemd[1]: Starting Music Player Daemon... Apr 26 21:52:08 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:08 volumio volumio[21734]: info: [1745718728073] CoreMusicLibrary::Adding element Last_100 Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:08 volumio volumio[21734]: info: [1745718728076] CoreMusicLibrary::Adding element Webradio Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 21:52:08 volumio volumio[21734]: info: Initializing BBC Radios Apr 26 21:52:08 volumio sudo[22190]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 21:52:08 volumio sudo[22190]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:08 volumio sudo[22190]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: Adding Calm Radio to Browse Sources Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:08 volumio volumio[21734]: info: [1745718728150] CoreMusicLibrary::Adding element Calm Radio Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source Calm Radio Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:08 volumio volumio[21734]: info: [1745718728157] CoreMusicLibrary::Adding element YouTube2 Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source Calm Radio Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source YouTube2 Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:08 volumio volumio[21734]: info: [1745718728161] CoreMusicLibrary::Adding element YouTube Music Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source Calm Radio Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source YouTube2 Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source YouTube Music Apr 26 21:52:08 volumio volumio[21734]: info: AutoStart - onStart Apr 26 21:52:08 volumio volumio[21734]: info: [now-playing] ConfigUpdater: config is up to date. Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:08 volumio volumio[21734]: info: [1745718728182] CoreMusicLibrary::Adding element 80s80s Radio Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source Calm Radio Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source YouTube2 Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source YouTube Music Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source 80s80s Radio Apr 26 21:52:08 volumio volumio[21734]: info: Loading i18n strings for locale fr Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:08 volumio volumio[21734]: info: [1745718728227] CoreMusicLibrary::Adding element Radio Paradise Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source Calm Radio Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source YouTube2 Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source YouTube Music Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source 80s80s Radio Apr 26 21:52:08 volumio volumio[21734]: Cannot find translation for source Radio Paradise Apr 26 21:52:08 volumio volumio[21734]: info: EVO SABRE : Starting Plugin Apr 26 21:52:08 volumio volumio[21734]: info: Loading i18n strings for locale fr Apr 26 21:52:08 volumio volumio[21734]: info: Configuring GPIO pins Apr 26 21:52:08 volumio volumio[21734]: error: Plugin audiophonicsonoff failed to start! Error: EBUSY: resource busy or locked, write Apr 26 21:52:08 volumio volumio[21734]: info: Loading i18n strings for locale fr Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 21:52:08 volumio volumio[21734]: info: Volumio Calling Home Apr 26 21:52:08 volumio sudo[22220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 26 21:52:08 volumio sudo[22220]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:08 volumio sudo[22223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 26 21:52:08 volumio sudo[22223]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:08 volumio sudo[22225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 26 21:52:08 volumio sudo[22225]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:08 volumio sudo[22220]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:08 volumio systemd[1]: Reloading. Apr 26 21:52:08 volumio volumio[21734]: info: [now-playing] App is listening on port 4004. Apr 26 21:52:08 volumio volumio[21734]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Apr 26 21:52:08 volumio volumio[21734]: info: Discovery: adding 389c0df0-bad5-49c6-b7b2-37008c21d9bf Apr 26 21:52:08 volumio volumio[21734]: info: Discovery: Found device Volumio Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:08 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:08 volumio volumio[21734]: info: touch_display: Backlight interface detected. Apr 26 21:52:08 volumio volumio[21734]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 26 21:52:08 volumio volumio[21734]: info: minidlna: Found miniDLNA version 1.2.1 Apr 26 21:52:08 volumio volumio[21734]: info: minidlna: Starting minidlna.service Apr 26 21:52:08 volumio sudo[22243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 26 21:52:08 volumio sudo[22243]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:08 volumio volumio[21734]: info: MPD Permissions set Apr 26 21:52:08 volumio volumio[21734]: info: MPD Permissions set Apr 26 21:52:08 volumio sudo[22246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Apr 26 21:52:08 volumio sudo[22246]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:08 volumio volumio[21734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio 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. Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:09 volumio volumio[21734]: info: touch_display: Raspberry Pi Foundation touch screen detected. Apr 26 21:52:09 volumio sudo[22257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 26 21:52:09 volumio sudo[22257]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:09 volumio sudo[22257]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:09 volumio sudo[22260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Apr 26 21:52:09 volumio sudo[22260]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:09 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:52:09 volumio sudo[22260]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:09 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:52:09 volumio sudo[22263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 21:52:09 volumio sudo[22263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:09 volumio mpd[22196]: Apr 26 21:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 21:52:09 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:52:09 volumio sudo[22266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 21:52:09 volumio sudo[22266]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:09 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:09 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:09 volumio volumio[21734]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 26 21:52:09 volumio sudo[22269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 21:52:09 volumio sudo[22269]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:09 volumio volumio[21734]: info: touch_display: File permissions for backlight brightness control set. Apr 26 21:52:09 volumio systemd[1]: Started Music Player Daemon. Apr 26 21:52:09 volumio systemd[1]: Reloading. Apr 26 21:52:09 volumio sudo[22175]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:09 volumio sudo[22225]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:09 volumio volumio[21734]: info: Volumio called home Apr 26 21:52:09 volumio sudo[22185]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:09 volumio volumio[21734]: info: touch_display: systemctl daemon-reload succeeded. Apr 26 21:52:09 volumio sudo[22292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 26 21:52:09 volumio sudo[22292]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:09 volumio volumio[21734]: error: MPD error: The expression evaluated to a falsy value: Apr 26 21:52:09 volumio volumio[21734]: assert.ok(self.idling) Apr 26 21:52:09 volumio volumio[21734]: error: The expression evaluated to a falsy value: Apr 26 21:52:09 volumio volumio[21734]: assert.ok(self.idling) Apr 26 21:52:09 volumio volumio[21734]: info: MPD running with PID22196 Apr 26 21:52:09 volumio volumio[21734]: ,establishing connection Apr 26 21:52:09 volumio volumio[21734]: error: updateQueue error: null Apr 26 21:52:09 volumio volumio[21734]: error: updateQueue error: null Apr 26 21:52:09 volumio 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. Apr 26 21:52:10 volumio sudo[22223]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio volumio[21734]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 26 21:52:10 volumio systemd[1]: Reloading. Apr 26 21:52:10 volumio sudo[22327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 21:52:10 volumio sudo[22327]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:10 volumio sudo[22327]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio sudo[22330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 21:52:10 volumio sudo[22330]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:10 volumio sudo[22330]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio sudo[22333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 26 21:52:10 volumio sudo[22333]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:10 volumio 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. Apr 26 21:52:10 volumio sudo[22243]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio volumio[21734]: info: EVO SABRE : systemd cmd daemon-reload : success Apr 26 21:52:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 21:52:10 volumio sudo[22333]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio sudo[22246]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 21:52:10 volumio sudo[22292]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 21:52:10 volumio volumio[21734]: info: Upmpdcli Daemon Started Apr 26 21:52:10 volumio volumio[21734]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 26 21:52:10 volumio volumio[21734]: info: touch_display: Volumio Kiosk started. Apr 26 21:52:10 volumio sudo[22337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Apr 26 21:52:10 volumio sudo[22337]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:10 volumio sudo[22269]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 21:52:10 volumio sudo[22266]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio sudo[22263]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio systemd[1]: Stopping OLED Display Service for EVO SABRE... Apr 26 21:52:10 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:10 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:10 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:10 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:10 volumio volumio[21734]: info: Shairport-Sync Started Apr 26 21:52:10 volumio volumio[21734]: Error adding Membership: Error: addMembership EINVAL Apr 26 21:52:10 volumio volumio[21734]: info: Shairport-Sync Started Apr 26 21:52:10 volumio volumio[21734]: info: minidlna: systemctl start minidlna.service succeeded. Apr 26 21:52:10 volumio volumio[21734]: info: touch_display: X display number found: 0 Apr 26 21:52:10 volumio sudo[20963]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio systemd[1]: evo_oled2.service: Succeeded. Apr 26 21:52:10 volumio systemd[1]: Stopped OLED Display Service for EVO SABRE. Apr 26 21:52:10 volumio systemd[1]: Started OLED Display Service for EVO SABRE. Apr 26 21:52:10 volumio sudo[22337]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:10 volumio sudo[22344]: 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 Apr 26 21:52:10 volumio volumio[21734]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 26 21:52:10 volumio sudo[22344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:10 volumio volumio[21734]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Apr 26 21:52:10 volumio sudo[22354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Apr 26 21:52:10 volumio sudo[22354]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:10 volumio volumio[21734]: info: touch_display: X display number found: 0 Apr 26 21:52:10 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:10 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:10 volumio systemd[1]: Stopping evo_remote.service... Apr 26 21:52:10 volumio lircd-0.10.1[20975]: Notice: caught signal Apr 26 21:52:10 volumio lircd[20975]: lircd-0.10.1[20975]: Notice: caught signal Apr 26 21:52:10 volumio systemd[1]: evo_remote.service: Main process exited, code=killed, status=15/TERM Apr 26 21:52:10 volumio systemd[1]: evo_remote.service: Succeeded. Apr 26 21:52:10 volumio systemd[1]: Stopped evo_remote.service. Apr 26 21:52:10 volumio systemd[1]: evo_irexec.service: Succeeded. Apr 26 21:52:11 volumio volumio[21734]: info: touch_display: Setting screensaver timeout to 12 seconds. Apr 26 21:52:11 volumio systemd[1]: Starting lircd(8) initialization helper tool... Apr 26 21:52:11 volumio lircd-setup[22360]: /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. Apr 26 21:52:11 volumio lircd-setup[22360]: parser = configparser.SafeConfigParser() Apr 26 21:52:11 volumio systemd[1]: lircd-setup.service: Succeeded. Apr 26 21:52:11 volumio systemd[1]: Started lircd(8) initialization helper tool. Apr 26 21:52:11 volumio sudo[22354]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:11 volumio systemd[1]: Started evo_remote.service. Apr 26 21:52:11 volumio volumio[21734]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: lircd: Opening log, level: Info Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Version: lircd 0.10.1 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: System info: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux Apr 26 21:52:11 volumio sudo[22367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: Initial device: /dev/lirc0 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: [lirc] protocol is enabled Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: Initial device: /dev/lirc0 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: [lirc] protocol is enabled Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: driver: default Apr 26 21:52:11 volumio lircd[22362]: lircd-0.10.1[22362]: Info: lircd: Opening log, level: Info Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: output: /var/run/lirc/lircd Apr 26 21:52:11 volumio lircd[22362]: lircd-0.10.1[22362]: Warning: Running as root Apr 26 21:52:11 volumio lircd[22362]: lircd-0.10.1[22362]: Info: Using remote: ApEvo. Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: nodaemon: 1 Apr 26 21:52:11 volumio lircd[22362]: lircd-0.10.1[22362]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: logfile: syslog Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: immediate-init: 0 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: permission: 666 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: driver-options: Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: listen: 0 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: connect: (null) Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: userelease: 0 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: effective_user: (null) Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: release_suffix: _EVUP Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: allow_simulate: 0 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: repeat_max: 600 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Options: dynamic_codes: (null) Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Current driver: default Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Driver API version: 3 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Driver version: 0.10.0 Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: lircd: Opening log, level: Info Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Warning: Running as root Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: Using remote: ApEvo. Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 26 21:52:11 volumio sudo[22367]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:11 volumio systemd[1]: Starting lircd(8) initialization helper tool... Apr 26 21:52:11 volumio volumio[21734]: verbose: New Socket.io Connection to 192.168.2.146 from 192.168.2.113 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Apr 26 21:52:11 volumio lircd-setup[22369]: /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. Apr 26 21:52:11 volumio lircd-setup[22369]: parser = configparser.SafeConfigParser() Apr 26 21:52:11 volumio systemd[1]: lircd-setup.service: Succeeded. Apr 26 21:52:11 volumio systemd[1]: Started lircd(8) initialization helper tool. Apr 26 21:52:11 volumio systemd[1]: Started evo_irexec.service. Apr 26 21:52:11 volumio sudo[22367]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:11 volumio lircd[22362]: lircd-0.10.1[22362]: Notice: accepted new client on /var/run/lirc/lircd Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Notice: accepted new client on /var/run/lirc/lircd Apr 26 21:52:11 volumio lircd[22362]: lircd-0.10.1[22362]: Info: [lirc] protocol is enabled Apr 26 21:52:11 volumio lircd-0.10.1[22362]: Info: [lirc] protocol is enabled Apr 26 21:52:11 volumio volumio[21734]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Apr 26 21:52:11 volumio volumio[21734]: info: Completed starting Core Plugins Apr 26 21:52:11 volumio volumio[21734]: info: ------------------------------------------- Apr 26 21:52:11 volumio volumio[21734]: info: ----- MyVolumio plugins startup ---- Apr 26 21:52:11 volumio volumio[21734]: info: ------------------------------------------- Apr 26 21:52:11 volumio volumio[21734]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 26 21:52:12 volumio volumio[21734]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 26 21:52:12 volumio volumio[21734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 26 21:52:12 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:12 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:12 volumio volumio[21734]: info: CoreCommandRouter::volumioGetQueue Apr 26 21:52:12 volumio volumio[21734]: info: CoreStateMachine::getQueue Apr 26 21:52:12 volumio volumio[21734]: info: CorePlayQueue::getQueue Apr 26 21:52:12 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:12 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:13 volumio volumio[21734]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 26 21:52:14 volumio volumio[21734]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 26 21:52:14 volumio volumio-remote-updater[644]: [2025-04-26 21:52:14] [connect] Successful connection Apr 26 21:52:15 volumio volumio-remote-updater[644]: [2025-04-26 21:52:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745718734 101 Apr 26 21:52:15 volumio volumio[21734]: 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: 11 Apr 26 21:52:15 volumio volumio[21734]: Cannot compose Albumart path Apr 26 21:52:15 volumio volumio[21734]: Cannot compose Albumart path Apr 26 21:52:15 volumio volumio[21734]: Cannot compose Albumart path Apr 26 21:52:15 volumio volumio[21734]: Cannot compose Albumart path Apr 26 21:52:16 volumio volumio[21734]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 21:52:16 volumio volumio[21734]: info: Received Get System Info Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 21:52:16 volumio volumio[21734]: info: Discovery: Getting this device information Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: Listing playlists Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 21:52:16 volumio volumio[21734]: info: Received Get System Info Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 21:52:16 volumio volumio[21734]: info: Discovery: Getting this device information Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: Listing playlists Apr 26 21:52:16 volumio volumio[21734]: verbose: New Socket.io Connection to 192.168.2.146 from 192.168.2.113 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 21:52:16 volumio volumio[21734]: info: Received Get System Info Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 21:52:16 volumio volumio[21734]: info: Discovery: Getting this device information Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 21:52:16 volumio volumio[21734]: info: Received Get System Info Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 21:52:16 volumio volumio[21734]: info: Discovery: Getting this device information Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:16 volumio volumio[21734]: info: Listing playlists Apr 26 21:52:16 volumio volumio[21734]: info: CoreCommandRouter::volumioGetQueue Apr 26 21:52:16 volumio volumio[21734]: info: CoreStateMachine::getQueue Apr 26 21:52:16 volumio volumio[21734]: info: CorePlayQueue::getQueue Apr 26 21:52:17 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 21:52:20 volumio volumio[21734]: info: Adding plugin bluetooth to MyMusic Plugins Apr 26 21:52:20 volumio volumio[21734]: info: Adding plugin multiroom to MyMusic Plugins Apr 26 21:52:20 volumio volumio[21734]: info: Adding plugin metavolumio to MyMusic Plugins Apr 26 21:52:20 volumio volumio[21734]: info: Adding plugin cd_controller to MyMusic Plugins Apr 26 21:52:20 volumio volumio[21734]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 26 21:52:20 volumio volumio[21734]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 21:52:20 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:20 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:20 volumio volumio[21734]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 21:52:20 volumio volumio[21734]: info: MyVolumio login type: Token Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 26 21:52:20 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 26 21:52:21 volumio volumio[21734]: info: Starting Streaming Service Transparent Proxy Apr 26 21:52:21 volumio volumio[21734]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 26 21:52:21 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 26 21:52:21 volumio volumio[21734]: info: Streaming services startup Apr 26 21:52:21 volumio volumio[21734]: info: Starting Streaming Daemon Apr 26 21:52:21 volumio sudo[22407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 21:52:21 volumio sudo[22407]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:21 volumio sudo[22407]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:21 volumio volumio[21734]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 26 21:52:21 volumio volumio[21734]: error: Cannot start Volumio Streaming Daemon Apr 26 21:52:21 volumio volumio[21734]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 21:52:21 volumio volumio[21734]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 21:52:22 volumio volumio[21734]: STREAMING PROXY: Starting server on port 3245 Apr 26 21:52:22 volumio volumio[21734]: Node JS runtime: 14 Apr 26 21:52:22 volumio volumio[21734]: info: Enabling plugin audiophonicsonoff Apr 26 21:52:22 volumio volumio[21734]: info: Loading plugin "audiophonicsonoff"... Apr 26 21:52:22 volumio volumio[21734]: info: Applying required configuration parameters for plugin audiophonicsonoff Apr 26 21:52:22 volumio volumio[21734]: info: Audiophonics on/off initiated Apr 26 21:52:22 volumio volumio[21734]: info: PLUGIN START: audiophonicsonoff Apr 26 21:52:22 volumio volumio[21734]: info: Configuring GPIO pins Apr 26 21:52:22 volumio volumio[21734]: info: Error: Error: EBUSY: resource busy or locked, write Apr 26 21:52:23 volumio volumio[21734]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 26 21:52:24 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 21:52:24 volumio volumio[21734]: info: MyVolumio token set successfully Apr 26 21:52:24 volumio volumio[21734]: info: MYVOLUMIO: Adding device Apr 26 21:52:24 volumio volumio[21734]: info: MYVOLUMIO: Evaluating Server Apr 26 21:52:25 volumio volumio[21734]: info: MyVolumio Plan changed: superstar Apr 26 21:52:25 volumio volumio[21734]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 26 21:52:25 volumio volumio[21734]: info: Adding audio output: Apr 26 21:52:25 volumio volumio[21734]: info: MYVOLUMIO: Adding device Apr 26 21:52:25 volumio volumio[21734]: info: MYVOLUMIO: Evaluating Server Apr 26 21:52:25 volumio volumio[21734]: info: Enabling plugin audiophonicsonoff Apr 26 21:52:25 volumio volumio[21734]: info: Loading plugin "audiophonicsonoff"... Apr 26 21:52:25 volumio volumio[21734]: info: Applying required configuration parameters for plugin audiophonicsonoff Apr 26 21:52:25 volumio volumio[21734]: info: Audiophonics on/off initiated Apr 26 21:52:25 volumio volumio[21734]: info: PLUGIN START: audiophonicsonoff Apr 26 21:52:25 volumio volumio[21734]: info: Configuring GPIO pins Apr 26 21:52:25 volumio volumio[21734]: info: Error: Error: EBUSY: resource busy or locked, write Apr 26 21:52:25 volumio volumio[21734]: info: Remote config written successfully Apr 26 21:52:25 volumio volumio[21734]: info: Starting Tunnel 1 Apr 26 21:52:25 volumio volumio[21734]: info: Starting Tunnel Connection Checker Apr 26 21:52:25 volumio volumio[21734]: info: Completed starting MyVolumio Plugin Apr 26 21:52:25 volumio volumio[21734]: info: MYVolumio Device enabled Apr 26 21:52:25 volumio volumio[21734]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 26 21:52:25 volumio volumio[21734]: info: MyVolumio status changed Apr 26 21:52:25 volumio volumio[21734]: info: Streaming services startup Apr 26 21:52:25 volumio volumio[21734]: info: Starting Streaming Daemon Apr 26 21:52:25 volumio sudo[22458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 21:52:25 volumio sudo[22458]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:25 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 26 21:52:25 volumio sudo[22458]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:25 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 26 21:52:25 volumio volumio[21734]: info: [1745718745837] Starting BluetoothController Apr 26 21:52:25 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 26 21:52:26 volumio sudo[22461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 26 21:52:26 volumio sudo[22461]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:26 volumio sudo[22461]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:26 volumio volumio[21734]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Apr 26 21:52:26 volumio volumio[21734]: info: MRS: MultiRoom plugin initialized Apr 26 21:52:26 volumio volumio[21734]: info: MRS: STOPPING SNAPCLIENT Apr 26 21:52:26 volumio volumio[21734]: info: MRS: Snap server stop Apr 26 21:52:26 volumio sudo[22477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 26 21:52:26 volumio sudo[22477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:26 volumio volumio[21734]: info: MRS: STOPPING volumioStreaming Apr 26 21:52:26 volumio sudo[22477]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:26 volumio sudo[22480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 26 21:52:26 volumio sudo[22480]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:26 volumio sudo[22483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 26 21:52:26 volumio sudo[22483]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:26 volumio sudo[22480]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 26 21:52:26 volumio sudo[22486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 26 21:52:26 volumio sudo[22486]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:26 volumio sudo[22483]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:26 volumio sudo[22486]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 26 21:52:26 volumio volumio[21734]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 26 21:52:26 volumio volumio[21734]: info: Preparing to generate the ALSA configuration file Apr 26 21:52:26 volumio volumio[21734]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 26 21:52:26 volumio volumio[21734]: info: Reading ALSA contributions from plugins. Apr 26 21:52:26 volumio volumio[21734]: info: Setting Geolocation for MyVolumio to us2 Apr 26 21:52:26 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:26 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:26 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: MRS: Removed streaming files Apr 26 21:52:27 volumio volumio[21734]: info: MRS: volumioStreaming STOPPED Apr 26 21:52:27 volumio volumio[21734]: info: MRS: SNAPSERVER STOPPED Apr 26 21:52:27 volumio volumio[21734]: info: MRS: SNAPCLIENT STOPPED Apr 26 21:52:27 volumio volumio[21734]: error: Cannot start Volumio Streaming Daemon Apr 26 21:52:27 volumio volumio[21734]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 21:52:27 volumio volumio[21734]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 21:52:27 volumio volumio[21734]: info: Asound.conf file written Apr 26 21:52:27 volumio sudo[22489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 26 21:52:27 volumio sudo[22489]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:27 volumio sudo[22489]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:27 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Apr 26 21:52:27 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Apr 26 21:52:27 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Apr 26 21:52:27 volumio volumio[21734]: info: Output device has changed, restarting MPD Apr 26 21:52:27 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 26 21:52:27 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:52:27 volumio sudo[22494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 21:52:27 volumio sudo[22494]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:27 volumio sudo[22494]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:27 volumio volumio[21734]: info: Output device has changed, restarting Shairport Sync Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:27 volumio sudo[22497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 21:52:27 volumio sudo[22497]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 21:52:27 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:52:27 volumio volumio[21734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio systemd[1]: mpd.service: Succeeded. Apr 26 21:52:27 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 26 21:52:27 volumio volumio[21734]: info: MRS: Pushing multiroomSync output for this device Apr 26 21:52:27 volumio volumio[21734]: info: MRS: Pushing multiroomSync output Apr 26 21:52:27 volumio volumio[21734]: info: Adding audio output: Apr 26 21:52:27 volumio volumio[21734]: info: Adding audio output: Apr 26 21:52:27 volumio sudo[22506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Apr 26 21:52:27 volumio sudo[22506]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:27 volumio volumio[21734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio sudo[22506]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:27 volumio systemd[1]: Starting Music Player Daemon... Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 26 21:52:27 volumio volumio[21734]: info: Adding METAVOLUMIO REST API Endpoints Apr 26 21:52:27 volumio volumio[21734]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 26 21:52:27 volumio volumio[21734]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 26 21:52:27 volumio volumio[21734]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 26 21:52:27 volumio volumio[21734]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 26 21:52:27 volumio volumio[21734]: info: Preparing CD Folders Apr 26 21:52:27 volumio volumio[21734]: info: Adding CD REST API Endpoints Apr 26 21:52:27 volumio volumio[21734]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 26 21:52:27 volumio volumio[21734]: info: Starting UDEV Watcher for CD Apr 26 21:52:27 volumio volumio[21734]: info: Detecting CD presence with UDEV Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 26 21:52:27 volumio volumio[21734]: info: Adding inputs REST Endpoints Apr 26 21:52:27 volumio volumio[21734]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 26 21:52:27 volumio volumio[21734]: info: Scanning Audio Inputs Apr 26 21:52:27 volumio sudo[22502]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 21:52:27 volumio sudo[22502]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:27 volumio sudo[22502]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:27 volumio volumio[21734]: info: Checking against Known Cards name Apr 26 21:52:27 volumio volumio[21734]: info: Adding Server instance for streaming Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 26 21:52:27 volumio volumio[21734]: error: Hi Res Audio Failed Login: Missing Login Data Apr 26 21:52:27 volumio volumio[21734]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 26 21:52:27 volumio volumio[21734]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 26 21:52:27 volumio volumio[21734]: info: Refreshing QOBUZ token Apr 26 21:52:27 volumio volumio[21734]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 26 21:52:27 volumio sudo[22517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:52:27 volumio sudo[22517]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:27 volumio volumio[21734]: info: Stopping AccessToken refresher cron Apr 26 21:52:27 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Apr 26 21:52:27 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 26 21:52:27 volumio systemd[1]: vtcs.service: Succeeded. Apr 26 21:52:27 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Apr 26 21:52:27 volumio sudo[22517]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:27 volumio volumio[21734]: info: AccessToken refresher cron started Apr 26 21:52:27 volumio volumio[21734]: info: Adding TIDAL REST API Endpoints Apr 26 21:52:27 volumio volumio[21734]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 26 21:52:27 volumio volumio[21734]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 26 21:52:27 volumio volumio[21734]: info: Bluetooth name changed to Volumio Apr 26 21:52:27 volumio volumio[21734]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 26 21:52:27 volumio volumio[21734]: info: MPD Permissions set Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:27 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:52:27 volumio sudo[22526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 21:52:27 volumio sudo[22526]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 21:52:27 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 21:52:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 21:52:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 21:52:27 volumio sudo[22526]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:27 volumio volumio[21734]: info: Shairport-Sync Started Apr 26 21:52:27 volumio volumio[21734]: info: MRS: Found cast device: TPM171E-6fc840a3a941d3a917edbcfe4402aeaf Apr 26 21:52:27 volumio volumio[21734]: info: Adding audio output: Apr 26 21:52:28 volumio mpd[22515]: Apr 26 21:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 21:52:28 volumio volumio[21734]: info: Updating MyVolumio device info Apr 26 21:52:28 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:28 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:28 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:28 volumio volumio[21734]: info: Access Token successfully retrieved Apr 26 21:52:28 volumio volumio[21734]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 21:52:28 volumio volumio[21734]: info: [1745718748142] CoreMusicLibrary::Adding element QOBUZ Apr 26 21:52:28 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 21:52:28 volumio volumio[21734]: Cannot find translation for source Calm Radio Apr 26 21:52:28 volumio volumio[21734]: Cannot find translation for source YouTube2 Apr 26 21:52:28 volumio volumio[21734]: Cannot find translation for source YouTube Music Apr 26 21:52:28 volumio volumio[21734]: Cannot find translation for source 80s80s Radio Apr 26 21:52:28 volumio volumio[21734]: Cannot find translation for source Radio Paradise Apr 26 21:52:28 volumio volumio[21734]: Cannot find translation for source QOBUZ Apr 26 21:52:28 volumio volumio[21734]: info: Stopping AccessToken refresher cron for QOBUZ Apr 26 21:52:28 volumio volumio[21734]: info: AccessToken refresher cron started for QOBUZ Apr 26 21:52:28 volumio volumio[21734]: info: Adding QOBUZ REST API Endpoints Apr 26 21:52:28 volumio volumio[21734]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 26 21:52:28 volumio volumio[21734]: info: AutoStart - Plugin is starting Apr 26 21:52:28 volumio volumio[21734]: info: CoreCommandRouter::volumioGetQueue Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::getQueue Apr 26 21:52:28 volumio volumio[21734]: info: CorePlayQueue::getQueue Apr 26 21:52:28 volumio volumio[21734]: info: AutoStart - start playing Apr 26 21:52:28 volumio volumio[21734]: info: AutoStart - start playing with no specific position Apr 26 21:52:28 volumio volumio[21734]: info: CoreCommandRouter::volumioPlay Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::play index 0 Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::stop Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::play index undefined Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 21:52:28 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:28 volumio volumio[21734]: info: CoreStateMachine::startPlaybackTimer Apr 26 21:52:28 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:28 volumio volumio[21734]: info: [1745718748170] ControllerQobuz::clearAddPlayTrack Apr 26 21:52:28 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 21:52:28 volumio volumio[21734]: error: MPD error: Error: This socket has been ended by the other party Apr 26 21:52:28 volumio volumio[21734]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 26 21:52:28 volumio volumio[21734]: error: MPD error: Error: This socket has been ended by the other party Apr 26 21:52:28 volumio volumio[21734]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 26 21:52:28 volumio volumio[21734]: error: MPD error: Error: This socket has been ended by the other party Apr 26 21:52:28 volumio volumio[21734]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 26 21:52:28 volumio systemd[1]: Started Music Player Daemon. Apr 26 21:52:28 volumio sudo[22497]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:28 volumio volumio[21734]: error: updateQueue error: null Apr 26 21:52:28 volumio volumio[21734]: info: Successfully Added MyVolumio device Apr 26 21:52:29 volumio volumio[21734]: info: Successfully Updated MyVolumio device Apr 26 21:52:30 volumio volumio[21734]: info: Setting Geolocation for MyVolumio to us1 Apr 26 21:52:30 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:30 volumio sudo[22555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 26 21:52:30 volumio sudo[22555]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:30 volumio sudo[22555]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:30 volumio volumio[21734]: info: Volumio BT Module successfully started Apr 26 21:52:30 volumio sudo[22558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 26 21:52:30 volumio sudo[22558]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:30 volumio autossh[15201]: received signal to exit (15) Apr 26 21:52:30 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 26 21:52:30 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 26 21:52:30 volumio systemd[1]: sshtunnel.service: Succeeded. Apr 26 21:52:30 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 26 21:52:30 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:52:30 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:52:30 volumio volumio[21734]: info: TidalConnect service stoped! Apr 26 21:52:30 volumio systemd[1]: Started MyVolumio SSH Tunnel. Apr 26 21:52:30 volumio sudo[22558]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:30 volumio autossh[22565]: port set to 0, monitoring disabled Apr 26 21:52:30 volumio autossh[22565]: starting ssh (count 1) Apr 26 21:52:30 volumio autossh[22565]: ssh child pid is 22569 Apr 26 21:52:30 volumio volumio[21734]: info: Remote SSH Started Apr 26 21:52:30 volumio volumio[21734]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 26 21:52:30 volumio volumio[21734]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 26 21:52:30 volumio volumio[21734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Apr 26 21:52:30 volumio sudo[22574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 26 21:52:30 volumio sudo[22574]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:52:30 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:30 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:30 volumio systemd[1]: Started Volumio Tidal Connect Service. Apr 26 21:52:30 volumio sudo[22574]: pam_unix(sudo:session): session closed for user root Apr 26 21:52:30 volumio volumio[21734]: info: Successfully Added MyVolumio device Apr 26 21:52:31 volumio volumio[21734]: info: Executing endpoint tc_getconfig Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 26 21:52:31 volumio vtcs[22576]: STARTING TidalConnect services, version: 1.5.0.45 Apr 26 21:52:31 volumio vtcs[22576]: STARTED TidalConnect services. Apr 26 21:52:31 volumio volumio[21734]: info: Executing endpoint tc_connect Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 26 21:52:31 volumio volumio[21734]: info: Connecting to TidalConnect Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::servicePushState Apr 26 21:52:31 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:52:31 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:52:31 volumio volumio[21734]: info: MRS: Pushing multiroomSync output update for this device Apr 26 21:52:31 volumio volumio[21734]: info: MRS: Pushing multiroomSync output Apr 26 21:52:31 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:31 volumio volumio[21734]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::servicePushState Apr 26 21:52:31 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:52:31 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:52:31 volumio volumio[21734]: info: MRS: Pushing multiroomSync output update for this device Apr 26 21:52:31 volumio volumio[21734]: info: MRS: Pushing multiroomSync output Apr 26 21:52:31 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:31 volumio volumio[21734]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Apr 26 21:52:31 volumio volumiossh-tunnel[22560]: Warning: Permanently added '[us2.myvolumio.org]:2222,[137.184.235.84]:2222' (RSA) to the list of known hosts. Apr 26 21:52:31 volumio volumio[21734]: info: Updating MyVolumio device info Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:31 volumio volumio[21734]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 21:52:31 volumio volumio[21734]: info: Successfully Updated MyVolumio device Apr 26 21:52:32 volumio volumio[21734]: info: MRS: Getting audio outputs on start Apr 26 21:52:32 volumio volumio[21734]: info: MRS: Requesting all other devices output Apr 26 21:52:33 volumio volumio[21734]: info: TidalConnect service started! Apr 26 21:52:33 volumio volumio[21734]: [Metrics] CommandRouter: 35s 137.33ms Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::volumiosetStartupVolume Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::Close All Modals sent Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::Close All Modals sent Apr 26 21:52:33 volumio volumio[21734]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 26 21:52:33 volumio volumio[21734]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Apr 26 21:52:33 volumio volumio[21734]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Apr 26 21:52:33 volumio volumio[21734]: aplay: main:828: audio open error: No such device Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 21:52:33 volumio volumio[21734]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 26 21:52:33 volumio volumio[21734]: info: Received Get System Version Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 21:52:33 volumio volumio[21734]: info: Received Get System Info Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 21:52:33 volumio volumio[21734]: info: Discovery: Getting this device information Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:33 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:33 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 21:52:34 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 26 21:52:34 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 21:52:34 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 26 21:52:35 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 21:52:35 volumio volumio[21734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Apr 26 21:52:35 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:52:35 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:52:38 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 21:52:38 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:52:38 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 21:52:38 volumio volumio-remote-updater[644]: No test mode Apr 26 21:52:38 volumio volumio-remote-updater[644]: No alpha test mode Apr 26 21:52:38 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 21:52:38 volumio volumio[21734]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 26 21:52:38 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 26 21:52:40 volumio volumio[21734]: info: BOOT COMPLETED Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::volumioGetBrowseSources Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 21:52:40 volumio volumio[21734]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Apr 26 21:52:40 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Apr 26 21:53:08 volumio volumio[21734]: info: CoreCommandRouter::volumioPlay Apr 26 21:53:08 volumio volumio[21734]: info: CoreStateMachine::play index undefined Apr 26 21:53:08 volumio volumio[21734]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 21:53:08 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:53:08 volumio volumio[21734]: info: CoreStateMachine::startPlaybackTimer Apr 26 21:53:08 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:53:08 volumio volumio[21734]: info: [1745718788612] ControllerQobuz::clearAddPlayTrack Apr 26 21:53:08 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 21:53:08 volumio volumio[21734]: info: sendMpdCommand stop took 1 milliseconds Apr 26 21:53:08 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 21:53:08 volumio volumio[21734]: info: Apr 26 21:53:08 volumio volumio[21734]: ---------------------------- MPD announces system playlist update Apr 26 21:53:08 volumio volumio[21734]: info: Ignoring MPD Status Update Apr 26 21:53:08 volumio volumio[21734]: info: sendMpdCommand clear took 2 milliseconds Apr 26 21:53:08 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/44900294" Apr 26 21:53:08 volumio volumio[21734]: error: updateQueue error: null Apr 26 21:53:08 volumio volumio[21734]: info: ------------------------------ 4ms Apr 26 21:53:08 volumio volumio[21734]: STREAMING PROXY: Handling url /?data=qobuz://song/44900294 Apr 26 21:53:08 volumio volumio[21734]: info: Executing endpoint getStreamUrlqobuz Apr 26 21:53:08 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 26 21:53:09 volumio volumio[21734]: info: getStreamUrl took 595 milliseconds Apr 26 21:53:09 volumio volumio[21734]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1506481&eid=44900294&fmt=27&profile=raw&app_id=539451548&cid=2729228&etsp=1745722389&hmac=iThhtZwHETf3U3TkpTNMm7ldCgw Apr 26 21:53:10 volumio volumio[21734]: STREAMING PROXY: Response: 200, length: 252113328 Apr 26 21:53:10 volumio volumio[21734]: STREAMING PROXY: Client dropped request, destroying Apr 26 21:53:10 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/44900294" Apr 26 21:53:10 volumio volumio[21734]: info: Apr 26 21:53:10 volumio volumio[21734]: ---------------------------- MPD announces system playlist update Apr 26 21:53:10 volumio volumio[21734]: info: Ignoring MPD Status Update Apr 26 21:53:10 volumio volumio[21734]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/44900294" took 3 milliseconds Apr 26 21:53:10 volumio volumio[21734]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 21:53:10 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand play Apr 26 21:53:10 volumio volumio[21734]: info: ------------------------------ 4ms Apr 26 21:53:10 volumio volumio[21734]: info: sendMpdCommand play took 3 milliseconds Apr 26 21:53:10 volumio volumio[21734]: STREAMING PROXY: Handling url /?data=qobuz://song/44900294 Apr 26 21:53:10 volumio volumio[21734]: info: Executing endpoint getStreamUrlqobuz Apr 26 21:53:10 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 26 21:53:10 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 21:53:10 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 26 21:53:11 volumio volumio[21734]: info: getStreamUrl took 532 milliseconds Apr 26 21:53:11 volumio volumio[21734]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1506481&eid=44900294&fmt=27&profile=raw&app_id=539451548&cid=2729228&etsp=1745722390&hmac=Pwcu5HwUcRyJUbrIHvRkNiJFP88 Apr 26 21:53:12 volumio volumio[21734]: STREAMING PROXY: Response: 200, length: 252113328 Apr 26 21:53:12 volumio volumio[21734]: info: Apr 26 21:53:12 volumio volumio[21734]: ---------------------------- MPD announces state update: player Apr 26 21:53:12 volumio volumio[21734]: info: ControllerMpd::getState Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand status Apr 26 21:53:12 volumio volumio[21734]: info: Apr 26 21:53:12 volumio volumio[21734]: ---------------------------- MPD announces state update: player Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 21:53:12 volumio volumio[21734]: info: sendMpdCommand status took 9 milliseconds Apr 26 21:53:12 volumio volumio[21734]: info: ControllerMpd::getState Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand status Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::parseState Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 21:53:12 volumio volumio[21734]: info: sendMpdCommand clearerror took 4 milliseconds Apr 26 21:53:12 volumio volumio[21734]: info: sendMpdCommand status took 4 milliseconds Apr 26 21:53:12 volumio volumio[21734]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::parseState Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::parseTrackInfo Apr 26 21:53:12 volumio volumio[21734]: info: ControllerMpd::pushState Apr 26 21:53:12 volumio volumio[21734]: info: CoreCommandRouter::servicePushState Apr 26 21:53:12 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:53:12 volumio volumio[21734]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":391,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"44900294","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/44900294","trackType":"qobuz"} Apr 26 21:53:12 volumio volumio[21734]: verbose: CURRENT POSITION 0 Apr 26 21:53:12 volumio volumio[21734]: info: CoreStateMachine::syncState stateService pause Apr 26 21:53:12 volumio volumio[21734]: info: CoreStateMachine::syncState currentStatus stop Apr 26 21:53:12 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:53:12 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 21:53:12 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:53:12 volumio volumio[21734]: info: MRS: Pushing multiroomSync output update for this device Apr 26 21:53:12 volumio volumio[21734]: info: MRS: Pushing multiroomSync output Apr 26 21:53:12 volumio volumio[21734]: info: ------------------------------ 53ms Apr 26 21:53:12 volumio volumio[21734]: info: sendMpdCommand playlistinfo took 48 milliseconds Apr 26 21:53:12 volumio volumio[21734]: verbose: ControllerMpd::parseTrackInfo Apr 26 21:53:12 volumio volumio[21734]: info: ControllerMpd::pushState Apr 26 21:53:12 volumio volumio[21734]: info: CoreCommandRouter::servicePushState Apr 26 21:53:12 volumio volumio[21734]: info: CorePlayQueue::getTrack 0 Apr 26 21:53:12 volumio volumio[21734]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":391,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"44900294","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/44900294","trackType":"qobuz"} Apr 26 21:53:12 volumio volumio[21734]: verbose: CURRENT POSITION 0 Apr 26 21:53:12 volumio volumio[21734]: info: CoreStateMachine::syncState stateService pause Apr 26 21:53:12 volumio volumio[21734]: info: CoreStateMachine::syncState currentStatus stop Apr 26 21:53:12 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:53:12 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 21:53:12 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:53:12 volumio volumio[21734]: info: MRS: Pushing multiroomSync output update for this device Apr 26 21:53:12 volumio volumio[21734]: info: MRS: Pushing multiroomSync output Apr 26 21:53:12 volumio volumio[21734]: info: ------------------------------ 101ms Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 21:53:37 volumio volumio[21734]: info: Getting Alsa Cards List without I2S DAC Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: Invalid card number. Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 26 21:53:37 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 21:53:54 volumio volumio[21734]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 26 21:53:54 volumio volumio[21734]: info: Preparing to save Alsa Options, stopping services first Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::volumioGetState Apr 26 21:53:54 volumio volumio[21734]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":true,"i2sid":{"value":"audiophonics-es9028q2m-dac","label":"Audiophonics I-Sabre ES9028Q2M"}} Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:54 volumio volumio[21734]: info: Enabling I2S DAC: Audiophonics I-Sabre ES9028Q2M Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:54 volumio sudo[22962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 26 21:53:54 volumio sudo[22962]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:54 volumio sudo[22962]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:54 volumio sudo[22965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r allo-piano-dac-pcm512x-audio Apr 26 21:53:54 volumio sudo[22965]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:54 volumio sudo[22965]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:54 volumio volumio[21734]: info: Removed overlay allo-piano-dac-pcm512x-audio Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:54 volumio sudo[22975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay i-sabre-q2m Apr 26 21:53:54 volumio sudo[22975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/#sound-dai-cells Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/compatible Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/reg Apr 26 21:53:54 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/status Apr 26 21:53:54 volumio sudo[22975]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:54 volumio volumio[21734]: info: Found match in i2s Card Database: setting mixer Digital for card Audiophonics I-Sabre ES9028Q2M Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 26 21:53:54 volumio volumio[21734]: info: Updating Volume Controller Parameters: Device: 1 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 26 21:53:54 volumio volumio[21734]: info: Disabling external Volume Control Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 21:53:54 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 26 21:53:54 volumio volumio[21734]: info: Preparing to generate the ALSA configuration file Apr 26 21:53:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:53:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 26 21:53:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:53:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Apr 26 21:53:55 volumio volumio[21734]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 26 21:53:55 volumio volumio[21734]: info: Reading ALSA contributions from plugins. Apr 26 21:53:55 volumio volumio[21734]: info: I2S Param [object Object] successfully enabled Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 21:53:55 volumio volumio[21734]: info: Getting Alsa Cards List without I2S DAC Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 21:53:55 volumio volumio[21734]: info: VolumeController:: Volume=76 Mute =true Apr 26 21:53:55 volumio volumio[21734]: info: CoreStateMachine::pushState Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::volumioPushState Apr 26 21:53:55 volumio volumio[21734]: info: MRS: Pushing multiroomSync output update for this device Apr 26 21:53:55 volumio volumio[21734]: info: MRS: Pushing multiroomSync output Apr 26 21:53:55 volumio volumio[21734]: info: Asound.conf file written Apr 26 21:53:55 volumio sudo[23001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 26 21:53:55 volumio sudo[23001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23001]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Apr 26 21:53:55 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Apr 26 21:53:55 volumio volumio[21734]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Apr 26 21:53:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 26 21:53:55 volumio volumio[21734]: info: Output device has changed, restarting MPD Apr 26 21:53:55 volumio sudo[23007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 21:53:55 volumio sudo[23007]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23007]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio volumio[21734]: info: Output device has changed, restarting Shairport Sync Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:55 volumio sudo[23010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 21:53:55 volumio sudo[23010]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio volumio[21734]: STREAMING PROXY: Client dropped request, destroying Apr 26 21:53:55 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 21:53:55 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:53:55 volumio volumio[21734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio systemd[1]: mpd.service: Succeeded. Apr 26 21:53:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 21:53:55 volumio vtcs[22576]: [2025-04-26 21:53:55.527] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 26 21:53:55 volumio vtcs[22576]: [2025-04-26 21:53:55.527] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 26 21:53:55 volumio vtcs[22576]: [2025-04-26 21:53:55.527] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Apr 26 21:53:55 volumio systemd[1]: Starting Music Player Daemon... Apr 26 21:53:55 volumio volumio[21734]: info: Output device has changed, restarting MPD Apr 26 21:53:55 volumio sudo[23019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:55 volumio sudo[23021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 21:53:55 volumio sudo[23021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23019]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio volumio[21734]: info: Output device has changed, restarting Shairport Sync Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:55 volumio sudo[23021]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Apr 26 21:53:55 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 26 21:53:55 volumio systemd[1]: vtcs.service: Succeeded. Apr 26 21:53:55 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Apr 26 21:53:55 volumio sudo[23024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 21:53:55 volumio sudo[23024]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23019]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio sudo[23016]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 21:53:55 volumio sudo[23016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23016]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 26 21:53:55 volumio systemd[1]: mpd.service: Succeeded. Apr 26 21:53:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 21:53:55 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:53:55 volumio volumio[21734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio systemd[1]: Starting Music Player Daemon... Apr 26 21:53:55 volumio sudo[23036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:55 volumio sudo[23036]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:55 volumio sudo[23039]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23036]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio sudo[23032]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 21:53:55 volumio sudo[23032]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio sudo[23032]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:55 volumio sudo[23043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:55 volumio sudo[23043]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:55 volumio volumio[21734]: info: MPD Permissions set Apr 26 21:53:55 volumio volumio[21734]: info: MPD Permissions set Apr 26 21:53:55 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio sudo[23043]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio sudo[23039]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:55 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:53:55 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:53:55 volumio sudo[23058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 21:53:55 volumio sudo[23058]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio volumio[21734]: info: Preparing to generate the ALSA configuration file Apr 26 21:53:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 21:53:56 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 21:53:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 21:53:56 volumio sudo[23061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 21:53:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 21:53:56 volumio sudo[23061]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio sudo[23058]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio volumio[21734]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 26 21:53:56 volumio volumio[21734]: info: Reading ALSA contributions from plugins. Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 21:53:56 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 26 21:53:56 volumio volumio[21734]: info: Shairport-Sync Started Apr 26 21:53:56 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 21:53:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:56 volumio volumio[21734]: info: Asound.conf file unchanged, so no further update is needed Apr 26 21:53:56 volumio volumio[21734]: info: Output device has changed, restarting MPD Apr 26 21:53:56 volumio sudo[23061]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio sudo[23067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 21:53:56 volumio sudo[23067]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio volumio[21734]: info: Output device has changed, restarting Shairport Sync Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:56 volumio sudo[23067]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio sudo[23072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 21:53:56 volumio sudo[23072]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 26 21:53:56 volumio systemd[1]: mpd.service: Succeeded. Apr 26 21:53:56 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 21:53:56 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:53:56 volumio volumio[21734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio systemd[1]: Starting Music Player Daemon... Apr 26 21:53:56 volumio volumio[21734]: info: Preparing to generate the ALSA configuration file Apr 26 21:53:56 volumio sudo[23081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:56 volumio sudo[23081]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio sudo[23081]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:56 volumio sudo[23085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:56 volumio sudo[23085]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio volumio[21734]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 26 21:53:56 volumio volumio[21734]: info: Reading ALSA contributions from plugins. Apr 26 21:53:56 volumio sudo[23077]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 21:53:56 volumio sudo[23077]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio volumio[21734]: info: MPD Permissions set Apr 26 21:53:56 volumio volumio[21734]: info: Shairport-Sync Started Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio sudo[23077]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio sudo[23085]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: Starting Shairport Sync Apr 26 21:53:56 volumio volumio[21734]: info: Asound.conf file unchanged, so no further update is needed Apr 26 21:53:56 volumio volumio[21734]: info: Output device has changed, restarting MPD Apr 26 21:53:56 volumio sudo[23096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 21:53:56 volumio sudo[23096]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio sudo[23099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 21:53:56 volumio sudo[23099]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 21:53:56 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 21:53:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 21:53:56 volumio sudo[23099]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio volumio[21734]: info: Output device has changed, restarting Shairport Sync Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 21:53:56 volumio sudo[23103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 21:53:56 volumio sudo[23103]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 21:53:56 volumio volumio[21734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 21:53:56 volumio volumio[21734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 21:53:56 volumio volumio[21734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 21:53:56 volumio sudo[23096]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 26 21:53:56 volumio systemd[1]: mpd.service: Succeeded. Apr 26 21:53:56 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 21:53:56 volumio systemd[1]: Starting Music Player Daemon... Apr 26 21:53:56 volumio sudo[23113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:56 volumio sudo[23113]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:56 volumio sudo[23113]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 21:53:56 volumio sudo[23117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 21:53:56 volumio sudo[23117]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio volumio[21734]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 21:53:56 volumio volumio[21734]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Apr 26 21:53:56 volumio volumio[21734]: info: PLUGIN onReboot : networkfs Apr 26 21:53:56 volumio volumio[21734]: info: PLUGIN onReboot : audiophonicsonoff Apr 26 21:53:56 volumio volumio[21734]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 21:53:56 volumio volumio[21734]: TypeError: Cannot read property 'writeSync' of undefined Apr 26 21:53:56 volumio volumio[21734]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Apr 26 21:53:56 volumio volumio[21734]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Apr 26 21:53:56 volumio volumio[21734]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Apr 26 21:53:56 volumio volumio[21734]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 26 21:53:56 volumio volumio[21734]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 26 21:53:56 volumio volumio[21734]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Apr 26 21:53:56 volumio volumio[21734]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Apr 26 21:53:56 volumio volumio[21734]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Apr 26 21:53:56 volumio volumio[21734]: at Socket.emit (events.js:315:20) Apr 26 21:53:56 volumio volumio[21734]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 26 21:53:56 volumio volumio[21734]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 26 21:53:56 volumio volumio[21734]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 21:53:56 volumio sudo[23117]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:56 volumio sudo[23114]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 21:53:56 volumio sudo[23114]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 21:53:56 volumio sudo[23114]: pam_unix(sudo:session): session closed for user root Apr 26 21:53:57 volumio sudo[23132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 21:52 Apr 26 21:53:57 volumio sudo[23132]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"