-- Logs begin at Thu 2024-10-03 15:01:14 CEST, end at Thu 2024-10-03 15:07:19 CEST. -- Oct 03 15:06:05 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Oct 03 15:06:07 volumio volumio[935]: info: Executing endpoint metavolumio Oct 03 15:06:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 15:06:07 volumio volumio[935]: info: Executing endpoint metavolumio Oct 03 15:06:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 15:06:07 volumio volumio[935]: info: Executing endpoint metavolumio Oct 03 15:06:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 15:06:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 15:06:19 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Oct 03 15:06:19 volumio volumio[935]: info: CoreStateMachine::play index undefined Oct 03 15:06:19 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 15:06:19 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:06:19 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Oct 03 15:06:19 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:06:19 volumio volumio[935]: info: [1727960779637] ControllerUPNPBrowser::resume Oct 03 15:06:19 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Oct 03 15:06:19 volumio volumio[935]: info: sendMpdCommand play took 2 milliseconds Oct 03 15:06:22 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Oct 03 15:06:22 volumio volumio[935]: info: CoreStateMachine::play index undefined Oct 03 15:06:22 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 15:06:22 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:06:22 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Oct 03 15:06:22 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:06:22 volumio volumio[935]: info: [1727960782818] ControllerUPNPBrowser::resume Oct 03 15:06:22 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Oct 03 15:06:22 volumio volumio[935]: info: sendMpdCommand play took 2 milliseconds Oct 03 15:06:26 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Oct 03 15:06:26 volumio volumio[935]: info: CoreStateMachine::play index undefined Oct 03 15:06:26 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 15:06:26 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:06:26 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Oct 03 15:06:26 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:06:26 volumio volumio[935]: info: [1727960786870] ControllerUPNPBrowser::resume Oct 03 15:06:26 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand play Oct 03 15:06:26 volumio volumio[935]: info: sendMpdCommand play took 1 milliseconds Oct 03 15:07:04 volumio volumio[935]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 15:07:04 volumio volumio[935]: info: Preparing to save Alsa Options, stopping services first Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Oct 03 15:07:04 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::volumioPause Oct 03 15:07:04 volumio volumio[935]: info: CoreStateMachine::pause Oct 03 15:07:04 volumio volumio[935]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 15:07:04 volumio volumio[935]: info: Found match in Cards Database: setting mixer PCM for card Headphones Oct 03 15:07:04 volumio vtcs[2151]: [2024-10-03 15:07:04.685] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 03 15:07:04 volumio vtcs[2151]: [2024-10-03 15:07:04.686] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 03 15:07:04 volumio vtcs[2151]: [2024-10-03 15:07:04.686] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 03 15:07:04 volumio sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:04 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:04 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 03 15:07:04 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:04 volumio systemd[1]: vtcs.service: Succeeded. Oct 03 15:07:04 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 15:07:04 volumio volumio[935]: info: Updating Volume Controller Parameters: Device: 0 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 15:07:04 volumio volumio[935]: info: Disabling external Volume Control Oct 03 15:07:04 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 15:07:04 volumio volumio[935]: info: Preparing to generate the ALSA configuration file Oct 03 15:07:04 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:04 volumio volumio[935]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 15:07:04 volumio volumio[935]: info: Reading ALSA contributions from plugins. Oct 03 15:07:04 volumio sudo[2307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:04 volumio sudo[2307]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:04 volumio sudo[2307]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:04 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 15:07:05 volumio volumio[935]: info: VolumeController:: Volume=86 Mute =false Oct 03 15:07:05 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:05 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:05 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:05 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:05 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:05 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:05 volumio volumio[935]: info: Asound.conf file written Oct 03 15:07:05 volumio sudo[2316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 15:07:05 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:05 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:05 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:05 volumio volumio[935]: Found hardware: "simple-card" "" "" "" "" Oct 03 15:07:05 volumio volumio[935]: Hardware is initialized using a generic method Oct 03 15:07:05 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:05 volumio volumio[935]: info: Output device has changed, restarting MPD Oct 03 15:07:05 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 15:07:05 volumio volumio[935]: info: Output device has changed, restarting Shairport Sync Oct 03 15:07:05 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:05 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:05 volumio sudo[2324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 15:07:05 volumio sudo[2324]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:05 volumio systemd[1]: Stopping Music Player Daemon... Oct 03 15:07:05 volumio volumio[935]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 15:07:05 volumio volumio[935]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:05 volumio systemd[1]: mpd.service: Succeeded. Oct 03 15:07:05 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 15:07:05 volumio systemd[1]: Starting Music Player Daemon... Oct 03 15:07:05 volumio volumio[935]: info: Output device has changed, restarting MPD Oct 03 15:07:05 volumio sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:05 volumio sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:05 volumio sudo[2337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 15:07:05 volumio sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:05 volumio volumio[935]: info: Output device has changed, restarting Shairport Sync Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:05 volumio sudo[2337]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:05 volumio sudo[2340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 15:07:05 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:05 volumio sudo[2335]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:05 volumio sudo[2331]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 15:07:05 volumio sudo[2331]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:05 volumio sudo[2331]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:05 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 03 15:07:05 volumio systemd[1]: mpd.service: Succeeded. Oct 03 15:07:05 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 15:07:06 volumio volumio[935]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 15:07:06 volumio volumio[935]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio systemd[1]: Starting Music Player Daemon... Oct 03 15:07:06 volumio sudo[2351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:06 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:06 volumio sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:06 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:06 volumio sudo[2357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:06 volumio sudo[2357]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:06 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:06 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:06 volumio sudo[2347]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 15:07:06 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:06 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:06 volumio volumio[935]: info: MPD Permissions set Oct 03 15:07:06 volumio volumio[935]: info: MPD Permissions set Oct 03 15:07:06 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio sudo[2357]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:06 volumio volumio[935]: info: Starting Shairport Sync Oct 03 15:07:06 volumio volumio[935]: info: Starting Shairport Sync Oct 03 15:07:06 volumio sudo[2373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 15:07:06 volumio sudo[2373]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:06 volumio volumio[935]: info: Preparing to generate the ALSA configuration file Oct 03 15:07:06 volumio sudo[2375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 15:07:06 volumio sudo[2375]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:06 volumio volumio[935]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 15:07:06 volumio volumio[935]: info: Reading ALSA contributions from plugins. Oct 03 15:07:06 volumio volumio[935]: info: Asound.conf file written Oct 03 15:07:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 03 15:07:06 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 03 15:07:06 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 03 15:07:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:06 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 15:07:06 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:06 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:06 volumio sudo[2373]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 03 15:07:06 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:06 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 03 15:07:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:06 volumio sudo[2375]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:07 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:07 volumio volumio[935]: Found hardware: "simple-card" "" "" "" "" Oct 03 15:07:07 volumio volumio[935]: Hardware is initialized using a generic method Oct 03 15:07:07 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:07 volumio volumio[935]: info: Output device has changed, restarting MPD Oct 03 15:07:07 volumio sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 15:07:07 volumio volumio[935]: info: Output device has changed, restarting Shairport Sync Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:07 volumio sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:07 volumio sudo[2389]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:07 volumio sudo[2391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 15:07:07 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:07 volumio systemd[1]: mpd.service: Succeeded. Oct 03 15:07:07 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 15:07:07 volumio volumio[935]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 15:07:07 volumio volumio[935]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio systemd[1]: Starting Music Player Daemon... Oct 03 15:07:07 volumio volumio[935]: info: Preparing to generate the ALSA configuration file Oct 03 15:07:07 volumio sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:07 volumio sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:07 volumio volumio[935]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 15:07:07 volumio volumio[935]: info: Reading ALSA contributions from plugins. Oct 03 15:07:07 volumio sudo[2401]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:07 volumio sudo[2397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 15:07:07 volumio sudo[2404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:07 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:07 volumio sudo[2404]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:07 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:07 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:07 volumio volumio[935]: info: MPD Permissions set Oct 03 15:07:07 volumio volumio[935]: info: Shairport-Sync Started Oct 03 15:07:07 volumio volumio[935]: info: Shairport-Sync Started Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio sudo[2404]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:07 volumio volumio[935]: info: Starting Shairport Sync Oct 03 15:07:07 volumio volumio[935]: info: Asound.conf file unchanged, so no further update is needed Oct 03 15:07:07 volumio volumio[935]: info: Output device has changed, restarting MPD Oct 03 15:07:07 volumio sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 15:07:07 volumio sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:07 volumio volumio[935]: info: Output device has changed, restarting Shairport Sync Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:07 volumio sudo[2417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 15:07:07 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 03 15:07:08 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 15:07:08 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 03 15:07:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:08 volumio volumio[935]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 15:07:08 volumio volumio[935]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:08 volumio sudo[2415]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:08 volumio systemd[1]: mpd.service: Succeeded. Oct 03 15:07:08 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 15:07:08 volumio systemd[1]: Starting Music Player Daemon... Oct 03 15:07:08 volumio sudo[2432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:08 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio volumio[935]: info: MRS: STARTING volumioStreaming Oct 03 15:07:08 volumio sudo[2436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:08 volumio sudo[2436]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:08 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:08 volumio sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 15:07:08 volumio sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:08 volumio sudo[2436]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio sudo[2438]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:08 volumio sudo[2441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 03 15:07:08 volumio sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio volumio[935]: info: MPD Permissions set Oct 03 15:07:08 volumio volumio[935]: info: Shairport-Sync Started Oct 03 15:07:08 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:08 volumio sudo[2431]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 15:07:08 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio systemd[1]: Stopping VolumioStreamingService... Oct 03 15:07:08 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:08 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio systemd[1]: volumioStreaming.service: Succeeded. Oct 03 15:07:08 volumio systemd[1]: Stopped VolumioStreamingService. Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:08 volumio systemd[1]: Started VolumioStreamingService. Oct 03 15:07:08 volumio sudo[2441]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:08 volumio volumio[935]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:08 volumio volumio[935]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:08 volumio volumio[935]: info: MRS: volumioStreaming STARTED Oct 03 15:07:08 volumio volumio[935]: info: Starting Shairport Sync Oct 03 15:07:08 volumio sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 03 15:07:08 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:08 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 15:07:08 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 03 15:07:08 volumio volumio[935]: info: CoreStateMachine::getcurrentVolume Oct 03 15:07:08 volumio volumio[935]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 15:07:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 03 15:07:08 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 03 15:07:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:08 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:08 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:08 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 15:07:08 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:09 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:09 volumio volumio[935]: info: Shairport-Sync Started Oct 03 15:07:09 volumio volumio[935]: (node:935) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 15:07:09 volumio volumio[935]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 03 15:07:09 volumio volumio[935]: (node:935) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 15:07:09 volumio volumio[935]: (node:935) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 15:07:09 volumio volumio[935]: (node:935) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 15:07:09 volumio volumio[935]: (node:935) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 15:07:09 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 03 15:07:09 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:09 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:09 volumio volumio[935]: info: VolumeController:: Volume=86 Mute =false Oct 03 15:07:09 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:09 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:09 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 15:07:09 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:09 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:09 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:09 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Oct 03 15:07:09 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Oct 03 15:07:09 volumio volumio[935]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 15:07:09 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:09 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:09 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:09 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:09 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:09 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:09 volumio volumio[935]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:09 volumio volumio[935]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:09 volumio volumio[935]: info: VolumeController:: Volume=86 Mute =false Oct 03 15:07:09 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:09 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:10 volumio volumio[935]: info: Executing endpoint tc_getconfig Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 15:07:10 volumio volumio[935]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:10 volumio volumio[935]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:10 volumio vtcs[2475]: STARTING TidalConnect services, version: 1.4.0.34 Oct 03 15:07:10 volumio sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 15:07:10 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:10 volumio sudo[2501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 15:07:10 volumio sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:10 volumio vtcs[2475]: STARTED TidalConnect services. Oct 03 15:07:10 volumio volumio[935]: info: Executing endpoint tc_connect Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 03 15:07:10 volumio volumio[935]: info: Connecting to TidalConnect Oct 03 15:07:10 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:10 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:10 volumio sudo[2501]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::servicePushState Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:10 volumio volumio[935]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 03 15:07:10 volumio volumio[935]: verbose: CURRENT POSITION 0 Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus pause Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::servicePushState Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:10 volumio volumio[935]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 03 15:07:10 volumio volumio[935]: verbose: CURRENT POSITION 0 Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::syncState stateService stop Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus pause Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:10 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:10 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:10 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:10 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:10 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:10 volumio volumio[935]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:10 volumio volumio[935]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:10 volumio sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 15:07:10 volumio sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:11 volumio sudo[2530]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:11 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:11 volumio volumio[935]: info: TidalConnect service stoped! Oct 03 15:07:11 volumio volumio[935]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:11 volumio volumio[935]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 15:07:11 volumio sudo[2542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 15:07:11 volumio sudo[2542]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:11 volumio sudo[2542]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:11 volumio mpd[2451]: Oct 03 15:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 03 15:07:12 volumio systemd[1]: Started Music Player Daemon. Oct 03 15:07:12 volumio sudo[2391]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:12 volumio sudo[2324]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:12 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:12 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:12 volumio volumio[935]: error: MPD error: The expression evaluated to a falsy value: Oct 03 15:07:12 volumio volumio[935]: assert.ok(self.idling) Oct 03 15:07:12 volumio volumio[935]: error: The expression evaluated to a falsy value: Oct 03 15:07:12 volumio volumio[935]: assert.ok(self.idling) Oct 03 15:07:12 volumio volumio[935]: error: MPD error: The expression evaluated to a falsy value: Oct 03 15:07:12 volumio volumio[935]: assert.ok(self.idling) Oct 03 15:07:12 volumio volumio[935]: error: The expression evaluated to a falsy value: Oct 03 15:07:12 volumio volumio[935]: assert.ok(self.idling) Oct 03 15:07:12 volumio volumio[935]: error: MPD error: The expression evaluated to a falsy value: Oct 03 15:07:12 volumio volumio[935]: assert.ok(self.idling) Oct 03 15:07:12 volumio volumio[935]: error: The expression evaluated to a falsy value: Oct 03 15:07:12 volumio volumio[935]: assert.ok(self.idling) Oct 03 15:07:12 volumio volumio[935]: error: updateQueue error: null Oct 03 15:07:12 volumio volumio[935]: info: TidalConnect service started! Oct 03 15:07:13 volumio volumio[935]: info: TidalConnect service started! Oct 03 15:07:13 volumio volumio[935]: info: TidalConnect service started! Oct 03 15:07:14 volumio volumio[935]: info: TidalConnect service started! Oct 03 15:07:14 volumio volumio[935]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 15:07:14 volumio volumio[935]: info: Preparing to save Alsa Options, stopping services first Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::volumioGetState Oct 03 15:07:14 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::volumioPause Oct 03 15:07:14 volumio volumio[935]: info: CoreStateMachine::pause Oct 03 15:07:14 volumio volumio[935]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"Headphones"},"i2s":true,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:14 volumio volumio[935]: info: Enabling I2S DAC: Adafruit MAX98357 Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:14 volumio sudo[2548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Oct 03 15:07:14 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:14 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:14 volumio volumio[935]: info: No Overlays Loaded Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:14 volumio sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay max98357a Oct 03 15:07:14 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:14 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Oct 03 15:07:14 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:14 volumio volumio[935]: info: Device 1 does not have any Mixer Control Available Oct 03 15:07:14 volumio vtcs[2475]: [2024-10-03 15:07:14.906] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 03 15:07:14 volumio vtcs[2475]: [2024-10-03 15:07:14.907] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 03 15:07:14 volumio vtcs[2475]: [2024-10-03 15:07:14.907] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 03 15:07:14 volumio sudo[2566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:14 volumio sudo[2566]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:14 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 03 15:07:14 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:14 volumio systemd[1]: vtcs.service: Succeeded. Oct 03 15:07:14 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 03 15:07:14 volumio sudo[2566]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:14 volumio volumio[935]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 15:07:14 volumio volumio[935]: info: Updating Volume Controller Parameters: Device: 1 Name: Adafruit MAX98357 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 15:07:15 volumio volumio[935]: info: Disabling external Volume Control Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 03 15:07:15 volumio volumio[935]: info: CoreStateMachine::pushState Oct 03 15:07:15 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 03 15:07:15 volumio volumio[935]: info: MRS: Pushing multiroomSync output update for this device Oct 03 15:07:15 volumio volumio[935]: info: MRS: Pushing multiroomSync output Oct 03 15:07:15 volumio kernel: hwmon hwmon1: Voltage normalised Oct 03 15:07:15 volumio volumio[935]: info: Preparing to generate the ALSA configuration file Oct 03 15:07:15 volumio volumio[935]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 15:07:15 volumio volumio[935]: info: Reading ALSA contributions from plugins. Oct 03 15:07:15 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::volumioGetQueue Oct 03 15:07:15 volumio volumio[935]: info: CoreStateMachine::getQueue Oct 03 15:07:15 volumio volumio[935]: info: CorePlayQueue::getQueue Oct 03 15:07:15 volumio sudo[2574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:15 volumio sudo[2574]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:15 volumio volumio[935]: error: Cannot enable I2S Param: Error: Command failed: /usr/bin/sudo /usr/bin/dtoverlay max98357a Oct 03 15:07:15 volumio volumio[935]: * Failed to apply overlay '0_max98357a' (kernel) Oct 03 15:07:15 volumio volumio[935]: info: TidalConnect service started! Oct 03 15:07:15 volumio sudo[2574]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 15:07:15 volumio volumio[935]: info: Getting Alsa Cards List without I2S DAC Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:15 volumio ntpd[789]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 15:07:15 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 15:07:15 volumio volumio[935]: info: Asound.conf file written Oct 03 15:07:16 volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 15:07:16 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:16 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:16 volumio volumio[935]: Found hardware: "simple-card" "" "" "" "" Oct 03 15:07:16 volumio volumio[935]: Hardware is initialized using a generic method Oct 03 15:07:16 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:16 volumio volumio[935]: info: Output device has changed, restarting MPD Oct 03 15:07:16 volumio sudo[2591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 15:07:16 volumio volumio[935]: info: Output device has changed, restarting Shairport Sync Oct 03 15:07:16 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:16 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:16 volumio sudo[2591]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio sudo[2591]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:16 volumio sudo[2593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 15:07:16 volumio sudo[2593]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio systemd[1]: Stopping Music Player Daemon... Oct 03 15:07:16 volumio volumio[935]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 15:07:16 volumio volumio[935]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 15:07:16 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:16 volumio systemd[1]: mpd.service: Succeeded. Oct 03 15:07:16 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 15:07:16 volumio systemd[1]: Starting Music Player Daemon... Oct 03 15:07:16 volumio volumio[935]: info: Output device has changed, restarting MPD Oct 03 15:07:16 volumio sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:16 volumio sudo[2603]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio sudo[2606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 15:07:16 volumio sudo[2606]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio sudo[2606]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:16 volumio sudo[2603]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:16 volumio volumio[935]: info: Output device has changed, restarting Shairport Sync Oct 03 15:07:16 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:16 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:16 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 15:07:16 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 03 15:07:16 volumio systemd[1]: mpd.service: Succeeded. Oct 03 15:07:16 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 15:07:16 volumio volumio[935]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 15:07:16 volumio volumio[935]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 15:07:16 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:16 volumio systemd[1]: Starting Music Player Daemon... Oct 03 15:07:16 volumio sudo[2619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:16 volumio sudo[2619]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:16 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:16 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:16 volumio sudo[2625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:16 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:16 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:16 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:16 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:16 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:16 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:17 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio volumio[935]: info: MPD Permissions set Oct 03 15:07:17 volumio volumio[935]: info: MPD Permissions set Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio sudo[2619]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio sudo[2615]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 15:07:17 volumio sudo[2615]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:17 volumio sudo[2615]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio sudo[2625]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: Starting Shairport Sync Oct 03 15:07:17 volumio volumio[935]: info: Starting Shairport Sync Oct 03 15:07:17 volumio sudo[2641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 15:07:17 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:17 volumio volumio[935]: info: Preparing to generate the ALSA configuration file Oct 03 15:07:17 volumio sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 15:07:17 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:17 volumio volumio[935]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 15:07:17 volumio volumio[935]: info: Reading ALSA contributions from plugins. Oct 03 15:07:17 volumio volumio[935]: info: Asound.conf file written Oct 03 15:07:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 03 15:07:17 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 03 15:07:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:17 volumio sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 15:07:17 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:17 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:17 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 03 15:07:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:17 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 03 15:07:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 03 15:07:17 volumio sudo[2643]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:17 volumio volumio[935]: Found hardware: "simple-card" "" "" "" "" Oct 03 15:07:17 volumio volumio[935]: Hardware is initialized using a generic method Oct 03 15:07:17 volumio volumio[935]: No state is present for card MAX98357A Oct 03 15:07:17 volumio volumio[935]: info: Output device has changed, restarting MPD Oct 03 15:07:17 volumio volumio[935]: info: Output device has changed, restarting Shairport Sync Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 15:07:17 volumio sudo[2654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 15:07:17 volumio sudo[2654]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:17 volumio sudo[2654]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:17 volumio sudo[2659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 15:07:17 volumio sudo[2659]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 03 15:07:17 volumio systemd[1]: mpd.service: Succeeded. Oct 03 15:07:17 volumio volumio[935]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 15:07:17 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 15:07:17 volumio volumio[935]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 15:07:17 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 15:07:18 volumio systemd[1]: Starting Music Player Daemon... Oct 03 15:07:18 volumio volumio[935]: info: Preparing to generate the ALSA configuration file Oct 03 15:07:18 volumio sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:18 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:18 volumio sudo[2671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 15:07:18 volumio sudo[2671]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:18 volumio volumio[935]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 15:07:18 volumio volumio[935]: info: Reading ALSA contributions from plugins. Oct 03 15:07:18 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 03 15:07:18 volumio volumio[935]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 03 15:07:18 volumio volumio[935]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 03 15:07:18 volumio sudo[2671]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:18 volumio volumio[935]: info: PLUGIN onReboot : networkfs Oct 03 15:07:18 volumio volumio[935]: info: PLUGIN onReboot : audiophonicsonoff Oct 03 15:07:18 volumio volumio[935]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 15:07:18 volumio volumio[935]: TypeError: Cannot read property 'writeSync' of undefined Oct 03 15:07:18 volumio volumio[935]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 03 15:07:18 volumio volumio[935]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 03 15:07:18 volumio volumio[935]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 03 15:07:18 volumio volumio[935]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 03 15:07:18 volumio volumio[935]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 03 15:07:18 volumio volumio[935]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 03 15:07:18 volumio volumio[935]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 03 15:07:18 volumio volumio[935]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 03 15:07:18 volumio volumio[935]: at Socket.emit (events.js:315:20) Oct 03 15:07:18 volumio volumio[935]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 03 15:07:18 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 03 15:07:18 volumio volumio[935]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 15:07:18 volumio sudo[2664]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 15:07:18 volumio sudo[2664]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 15:07:18 volumio sudo[2664]: pam_unix(sudo:session): session closed for user root Oct 03 15:07:19 volumio sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 15:06 Oct 03 15:07:19 volumio sudo[2687]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"