-- Logs begin at Tue 2025-06-10 08:38:38 PDT, end at Tue 2025-06-10 08:42:42 PDT. -- Jun 10 08:41:03 tv-volumio go-librespot[1416]: time="2025-06-10T08:41:03-07:00" level=trace msg="received accesspoint ping" Jun 10 08:41:03 tv-volumio go-librespot[1416]: time="2025-06-10T08:41:03-07:00" level=trace msg="received accesspoint pong ack" Jun 10 08:41:03 tv-volumio go-librespot[1416]: time="2025-06-10T08:41:03-07:00" level=trace msg="sent dealer ping" Jun 10 08:41:03 tv-volumio go-librespot[1416]: time="2025-06-10T08:41:03-07:00" level=trace msg="received dealer pong" Jun 10 08:41:33 tv-volumio go-librespot[1416]: time="2025-06-10T08:41:33-07:00" level=trace msg="sent dealer ping" Jun 10 08:41:33 tv-volumio go-librespot[1416]: time="2025-06-10T08:41:33-07:00" level=trace msg="received dealer pong" Jun 10 08:41:39 tv-volumio volumiologrotate[832]: ls: cannot access '/var/log/samba/log.wb-TV': No such file or directory Jun 10 08:41:39 tv-volumio volumiologrotate[832]: ls: cannot access 'VOLUMIO': No such file or directory Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 08:41:47 tv-volumio volumio[1160]: Invalid card number. Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 08:41:47 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 08:42:03 tv-volumio go-librespot[1416]: time="2025-06-10T08:42:03-07:00" level=trace msg="sent dealer ping" Jun 10 08:42:03 tv-volumio go-librespot[1416]: time="2025-06-10T08:42:03-07:00" level=trace msg="received dealer pong" Jun 10 08:42:19 tv-volumio volumio[1160]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 10 08:42:19 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 10 08:42:19 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jun 10 08:42:19 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:19 tv-volumio volumio[1160]: info: Restoring Previous Volume level: false true Jun 10 08:42:19 tv-volumio volumio[1160]: info: VolumeController::SetAlsaVolume100 Jun 10 08:42:19 tv-volumio volumio[1160]: info: Enable softmixer device for audio device number 5 Jun 10 08:42:19 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioStop Jun 10 08:42:19 tv-volumio volumio[1160]: info: CoreStateMachine::stop Jun 10 08:42:19 tv-volumio volumio[1160]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 08:42:19 tv-volumio volumio[1160]: info: Enable softmixer device for audio device undefined Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 08:42:20 tv-volumio volumio[1160]: info: Updating Volume Controller Parameters: Device: 5 Name: Bifrost Gen 5 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 08:42:20 tv-volumio volumio[1160]: info: Disabling external Volume Control Jun 10 08:42:20 tv-volumio volumio[1160]: info: Output device has changed, restarting MPD Jun 10 08:42:20 tv-volumio sudo[2408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 08:42:20 tv-volumio volumio[1160]: info: Output device has changed, restarting Shairport Sync Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio sudo[2408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:42:20 tv-volumio sudo[2408]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio sudo[2410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 08:42:20 tv-volumio sudo[2410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio systemd[1]: Stopping Music Player Daemon... Jun 10 08:42:20 tv-volumio volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 08:42:20 tv-volumio systemd[1]: mpd.service: Succeeded. Jun 10 08:42:20 tv-volumio systemd[1]: Stopped Music Player Daemon. Jun 10 08:42:20 tv-volumio volumio[1160]: info: Volume configurations have been set Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 08:42:20 tv-volumio volumio[1160]: info: Updating Volume Controller Parameters: Device: 5 Name: Bifrost Gen 5 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 08:42:20 tv-volumio systemd[1]: Starting Music Player Daemon... Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 08:42:20 tv-volumio volumio[1160]: info: Disabling external Volume Control Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreStateMachine::pushState Jun 10 08:42:20 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jun 10 08:42:20 tv-volumio volumio[1160]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreStateMachine::pushState Jun 10 08:42:20 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jun 10 08:42:20 tv-volumio sudo[2418]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 08:42:20 tv-volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio volumio[1160]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 08:42:20 tv-volumio volumio[1160]: info: MPD Permissions set Jun 10 08:42:20 tv-volumio volumio[1160]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 08:42:20 tv-volumio volumio[1160]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 08:42:20 tv-volumio volumio[1160]: info: Software Volume ALSA configuration written Jun 10 08:42:20 tv-volumio volumio[1160]: info: Preparing to generate the ALSA configuration file Jun 10 08:42:20 tv-volumio volumio[1160]: info: VolumeController:: Volume=undefined Mute =false Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreStateMachine::pushState Jun 10 08:42:20 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jun 10 08:42:20 tv-volumio volumio[1160]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 10 08:42:20 tv-volumio volumio[1160]: info: Reading ALSA contributions from plugins. Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 08:42:20 tv-volumio volumio[1160]: Invalid card number. Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 08:42:20 tv-volumio volumio[1160]: info: Starting Shairport Sync Jun 10 08:42:20 tv-volumio volumio[1160]: info: Asound.conf file written Jun 10 08:42:20 tv-volumio sudo[2441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 08:42:20 tv-volumio sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 08:42:20 tv-volumio sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 10 08:42:20 tv-volumio sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 08:42:20 tv-volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 08:42:20 tv-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 08:42:20 tv-volumio sudo[2441]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio volumio[1160]: info: Output device has changed, restarting MPD Jun 10 08:42:20 tv-volumio sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 08:42:20 tv-volumio sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio sudo[2452]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio volumio[1160]: info: Output device has changed, restarting Shairport Sync Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:42:20 tv-volumio sudo[2455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 08:42:20 tv-volumio sudo[2455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 08:42:20 tv-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 10 08:42:20 tv-volumio systemd[1]: mpd.service: Succeeded. Jun 10 08:42:20 tv-volumio systemd[1]: Stopped Music Player Daemon. Jun 10 08:42:20 tv-volumio volumio[1160]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jun 10 08:42:20 tv-volumio volumio[1160]: aplay: main:828: audio open error: No such device Jun 10 08:42:20 tv-volumio systemd[1]: Starting Music Player Daemon... Jun 10 08:42:20 tv-volumio volumio[1160]: info: Output device has changed, restarting MPD Jun 10 08:42:20 tv-volumio sudo[2461]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 08:42:20 tv-volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 08:42:20 tv-volumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio sudo[2466]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio volumio[1160]: info: Output device has changed, restarting Shairport Sync Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:42:20 tv-volumio sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 08:42:20 tv-volumio sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 10 08:42:20 tv-volumio systemd[1]: mpd.service: Succeeded. Jun 10 08:42:20 tv-volumio systemd[1]: Stopped Music Player Daemon. Jun 10 08:42:20 tv-volumio systemd[1]: Starting Music Player Daemon... Jun 10 08:42:20 tv-volumio volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 08:42:20 tv-volumio volumio[1160]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 08:42:20 tv-volumio sudo[2476]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 08:42:20 tv-volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 08:42:20 tv-volumio volumio[1160]: info: Disabling external Volume Control Jun 10 08:42:20 tv-volumio volumio[1160]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 08:42:20 tv-volumio volumio[1160]: info: MPD Permissions set Jun 10 08:42:20 tv-volumio volumio[1160]: info: MPD Permissions set Jun 10 08:42:20 tv-volumio volumio[1160]: info: Shairport-Sync Started Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreStateMachine::pushState Jun 10 08:42:20 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 08:42:20 tv-volumio volumio[1160]: info: Starting Shairport Sync Jun 10 08:42:20 tv-volumio volumio[1160]: info: Starting Shairport Sync Jun 10 08:42:20 tv-volumio sudo[2497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 08:42:20 tv-volumio sudo[2497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio sudo[2500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 08:42:20 tv-volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:20 tv-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 08:42:20 tv-volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 08:42:20 tv-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 08:42:20 tv-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 08:42:20 tv-volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio volumio[1160]: info: Shairport-Sync Started Jun 10 08:42:20 tv-volumio volumio[1160]: info: Shairport-Sync Started Jun 10 08:42:20 tv-volumio mpd[2481]: Jun 10 08:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 08:42:20 tv-volumio systemd[1]: Started Music Player Daemon. Jun 10 08:42:20 tv-volumio sudo[2471]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio sudo[2410]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:20 tv-volumio volumio[1160]: error: MPD error: The expression evaluated to a falsy value: Jun 10 08:42:20 tv-volumio volumio[1160]: assert.ok(self.idling) Jun 10 08:42:20 tv-volumio volumio[1160]: error: The expression evaluated to a falsy value: Jun 10 08:42:20 tv-volumio volumio[1160]: assert.ok(self.idling) Jun 10 08:42:20 tv-volumio volumio[1160]: error: MPD error: The expression evaluated to a falsy value: Jun 10 08:42:20 tv-volumio volumio[1160]: assert.ok(self.idling) Jun 10 08:42:20 tv-volumio volumio[1160]: error: The expression evaluated to a falsy value: Jun 10 08:42:20 tv-volumio volumio[1160]: assert.ok(self.idling) Jun 10 08:42:20 tv-volumio volumio[1160]: error: updateQueue error: null Jun 10 08:42:22 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 10 08:42:22 tv-volumio sudo[2511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 08:42:22 tv-volumio sudo[2511]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:22 tv-volumio systemd[1]: Stopping Music Player Daemon... Jun 10 08:42:22 tv-volumio systemd[1]: mpd.service: Succeeded. Jun 10 08:42:22 tv-volumio systemd[1]: Stopped Music Player Daemon. Jun 10 08:42:22 tv-volumio systemd[1]: Starting Music Player Daemon... Jun 10 08:42:22 tv-volumio sudo[2513]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 08:42:22 tv-volumio sudo[2513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:22 tv-volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:22 tv-volumio mpd[2515]: Jun 10 08:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 08:42:22 tv-volumio systemd[1]: Started Music Player Daemon. Jun 10 08:42:22 tv-volumio sudo[2511]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:22 tv-volumio volumio[1160]: error: updateQueue error: null Jun 10 08:42:24 tv-volumio volumio[1160]: info: VolumeController::SetAlsaVolumeNaN Jun 10 08:42:24 tv-volumio volumio[1160]: info: CoreStateMachine::pushState Jun 10 08:42:24 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:24 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 08:42:24 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioPushState Jun 10 08:42:24 tv-volumio volumio[1160]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 08:42:32 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jun 10 08:42:32 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:33 tv-volumio go-librespot[1416]: time="2025-06-10T08:42:33-07:00" level=trace msg="sent dealer ping" Jun 10 08:42:33 tv-volumio go-librespot[1416]: time="2025-06-10T08:42:33-07:00" level=trace msg="received dealer pong" Jun 10 08:42:34 tv-volumio sudo[2535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 08:42:34 tv-volumio sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:34 tv-volumio sudo[2535]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:34 tv-volumio sudo[2538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 08:42:34 tv-volumio sudo[2538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 08:42:34 tv-volumio sudo[2538]: pam_unix(sudo:session): session closed for user root Jun 10 08:42:34 tv-volumio volumio[1160]: verbose: New Socket.io Connection to 192.168.86.24 from 192.168.86.247 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jun 10 08:42:34 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 10 08:42:34 tv-volumio volumio[1160]: info: Received Get System Info Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 08:42:34 tv-volumio volumio[1160]: info: Discovery: Getting this device information Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jun 10 08:42:34 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jun 10 08:42:34 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:34 tv-volumio volumio[1160]: info: Listing playlists Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 10 08:42:34 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 08:42:35 tv-volumio wpa_supplicant[1057]: wlan0: Associated with 70:3a:cb:48:ff:77 Jun 10 08:42:35 tv-volumio dhcpcd[1070]: wlan0: carrier lost Jun 10 08:42:35 tv-volumio wpa_supplicant[1057]: wlan0: CTRL-EVENT-CONNECTED - Connection to 70:3a:cb:48:ff:77 completed [id=0 id_str=] Jun 10 08:42:35 tv-volumio wpa_supplicant[1057]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 10 08:42:35 tv-volumio avahi-daemon[825]: Withdrawing address record for 192.168.86.24 on wlan0. Jun 10 08:42:35 tv-volumio avahi-daemon[825]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.86.24. Jun 10 08:42:35 tv-volumio avahi-daemon[825]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 10 08:42:35 tv-volumio dhcpcd[1070]: wlan0: deleting route to 192.168.86.0/24 Jun 10 08:42:35 tv-volumio dhcpcd[1070]: wlan0: deleting default route via 192.168.86.1 Jun 10 08:42:35 tv-volumio volumio[1160]: info: Discovery: A device disappeared from network Jun 10 08:42:35 tv-volumio dhcpcd[1070]: wlan0: carrier acquired Jun 10 08:42:35 tv-volumio dhcpcd[1070]: wlan0: IAID 67:c2:82:ef Jun 10 08:42:35 tv-volumio dhcpcd[1070]: wlan0: soliciting an IPv6 router Jun 10 08:42:36 tv-volumio dhcpcd[1070]: wlan0: rebinding lease of 192.168.86.24 Jun 10 08:42:36 tv-volumio dhcpcd[1070]: wlan0: probing address 192.168.86.24/24 Jun 10 08:42:36 tv-volumio ntpd[941]: Deleting interface #3 wlan0, 192.168.86.24#123, interface stats: received=182, sent=183, dropped=0, active_time=218 secs Jun 10 08:42:36 tv-volumio ntpd[941]: 142.202.190.19 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 74.208.117.38 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 23.95.49.216 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 73.65.80.137 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 66.59.198.178 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 51.81.226.229 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 44.190.5.123 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 66.118.229.14 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 144.202.41.38 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 72.46.61.205 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 15.204.87.223 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 162.159.200.1 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 141.11.89.193 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 172.234.37.140 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 104.234.61.117 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 216.229.4.66 local addr 192.168.86.24 -> Jun 10 08:42:36 tv-volumio ntpd[941]: 198.199.14.20 local addr 192.168.86.24 -> Jun 10 08:42:37 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 08:42:37 tv-volumio volumio[1160]: info: Received Get System Info Jun 10 08:42:37 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 08:42:37 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 08:42:37 tv-volumio volumio[1160]: info: Discovery: Getting this device information Jun 10 08:42:37 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jun 10 08:42:37 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:37 tv-volumio volumio[1160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 08:42:39 tv-volumio volumio[1160]: info: Volumio Network Manager: Network status updated: 0 Jun 10 08:42:39 tv-volumio volumiologrotate[832]: ls: cannot access '/var/log/samba/log.wb-TV': No such file or directory Jun 10 08:42:39 tv-volumio volumiologrotate[832]: ls: cannot access 'VOLUMIO': No such file or directory Jun 10 08:42:41 tv-volumio dhcpcd[1070]: wlan0: leased 192.168.86.24 for 86400 seconds Jun 10 08:42:41 tv-volumio avahi-daemon[825]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.86.24. Jun 10 08:42:41 tv-volumio avahi-daemon[825]: New relevant interface wlan0.IPv4 for mDNS. Jun 10 08:42:41 tv-volumio avahi-daemon[825]: Registering new address record for 192.168.86.24 on wlan0.IPv4. Jun 10 08:42:41 tv-volumio dhcpcd[1070]: wlan0: adding route to 192.168.86.0/24 Jun 10 08:42:41 tv-volumio dhcpcd[1070]: wlan0: adding default route via 192.168.86.1 Jun 10 08:42:42 tv-volumio volumio[1160]: info: Discovery: adding 2f97e6dd-17d6-4f9e-9009-67d5cf4fdb99 Jun 10 08:42:42 tv-volumio volumio[1160]: info: Discovery: Found device TV Volumio Jun 10 08:42:42 tv-volumio volumio[1160]: info: CoreCommandRouter::volumioGetState Jun 10 08:42:42 tv-volumio volumio[1160]: info: CorePlayQueue::getTrack 0 Jun 10 08:42:42 tv-volumio volumio[1160]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 08:42:42 tv-volumio volumio[1160]: Error: The value NaN is not a number Jun 10 08:42:42 tv-volumio volumio[1160]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 10 08:42:42 tv-volumio volumio[1160]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 10 08:42:42 tv-volumio volumio[1160]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 10 08:42:42 tv-volumio volumio[1160]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 10 08:42:42 tv-volumio volumio[1160]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 10 08:42:42 tv-volumio volumio[1160]: at Browser.emit (events.js:315:20) Jun 10 08:42:42 tv-volumio volumio[1160]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 10 08:42:42 tv-volumio volumio[1160]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 10 08:42:42 tv-volumio volumio[1160]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 10 08:42:42 tv-volumio volumio[1160]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 10 08:42:42 tv-volumio volumio[1160]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 10 08:42:42 tv-volumio volumio[1160]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 10 08:42:42 tv-volumio volumio[1160]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 10 08:42:42 tv-volumio volumio[1160]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 08:42:42 tv-volumio sudo[2682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 08:41 Jun 10 08:42:42 tv-volumio sudo[2682]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"