-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Mon 2024-05-13 19:01:04 KST. -- May 13 19:00:00 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:00 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:00 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:00 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:01 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:01 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:01 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:01 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:02 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:02 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:02 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:02 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:02 volumio volumio[1211]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 13 19:00:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 13 19:00:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 19:00:02 volumio volumio[1211]: info: Enabling PI HDMI Output May 13 19:00:02 volumio volumio[1211]: info: Disabling I2S DAC: May 13 19:00:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC May 13 19:00:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 19:00:02 volumio volumio[1211]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out May 13 19:00:03 volumio sudo[7960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:03 volumio sudo[7960]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:03 volumio vtcs[3985]: [2024-05-13 19:00:03.040] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected May 13 19:00:03 volumio vtcs[3985]: [2024-05-13 19:00:03.069] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 13 19:00:03 volumio volumio[1211]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 13 19:00:03 volumio volumio[1211]: info: Disabling external Volume Control May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 19:00:03 volumio volumio[1211]: info: Preparing to generate the ALSA configuration file May 13 19:00:03 volumio volumio[1211]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 13 19:00:03 volumio volumio[1211]: info: Reading ALSA contributions from plugins. May 13 19:00:03 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 13 19:00:03 volumio sudo[7979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:03 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 13 19:00:03 volumio systemd[1]: vtcs.service: Succeeded. May 13 19:00:03 volumio sudo[7979]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:03 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:03 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:03 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:03 volumio sudo[7960]: pam_unix(sudo:session): session closed for user root May 13 19:00:03 volumio sudo[7979]: pam_unix(sudo:session): session closed for user root May 13 19:00:03 volumio volumio[1211]: info: Raspberry PI HDMI Output Enabled May 13 19:00:03 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:03 volumio sudo[7988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store May 13 19:00:03 volumio sudo[7988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:03 volumio volumio[1211]: info: VolumeController:: Volume=100 Mute =false May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:03 volumio volumio[1211]: info: CoreStateMachine::pushState May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState May 13 19:00:03 volumio sudo[7988]: pam_unix(sudo:session): session closed for user root May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 13 19:00:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 13 19:00:03 volumio sudo[8003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 19:00:03 volumio sudo[8003]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:03 volumio sudo[8003]: pam_unix(sudo:session): session closed for user root May 13 19:00:03 volumio volumio[1211]: info: No Overlays Loaded May 13 19:00:04 volumio volumio[1211]: info: Alsa Settings successfully stored May 13 19:00:04 volumio volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 13 19:00:04 volumio volumio[1211]: info: Asound.conf file written May 13 19:00:04 volumio sudo[8014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 13 19:00:04 volumio sudo[8014]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:04 volumio sudo[8014]: pam_unix(sudo:session): session closed for user root May 13 19:00:04 volumio volumio[1211]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 13 19:00:04 volumio volumio[1211]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 13 19:00:04 volumio volumio[1211]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 13 19:00:04 volumio volumio[1211]: info: Output device has changed, restarting MPD May 13 19:00:04 volumio sudo[8024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 19:00:04 volumio sudo[8024]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:04 volumio volumio[1211]: info: Output device has changed, restarting Shairport Sync May 13 19:00:04 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:04 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:04 volumio sudo[8024]: pam_unix(sudo:session): session closed for user root May 13 19:00:04 volumio sudo[8027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 19:00:04 volumio sudo[8027]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:04 volumio systemd[1]: Stopping Music Player Daemon... May 13 19:00:04 volumio volumio[1211]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 19:00:04 volumio volumio[1211]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 19:00:04 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:04 volumio volumio[1211]: info: Output device has changed, restarting MPD May 13 19:00:04 volumio sudo[8041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:04 volumio sudo[8045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 19:00:04 volumio sudo[8045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:04 volumio sudo[8041]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:04 volumio sudo[8045]: pam_unix(sudo:session): session closed for user root May 13 19:00:04 volumio volumio[1211]: info: Output device has changed, restarting Shairport Sync May 13 19:00:04 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:04 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:05 volumio sudo[8041]: pam_unix(sudo:session): session closed for user root May 13 19:00:05 volumio sudo[8049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 19:00:05 volumio sudo[8049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:05 volumio volumio[1211]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 19:00:05 volumio volumio[1211]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio sudo[8062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:05 volumio sudo[8062]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:05 volumio sudo[8065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:05 volumio sudo[8065]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:05 volumio sudo[8062]: pam_unix(sudo:session): session closed for user root May 13 19:00:05 volumio sudo[8065]: pam_unix(sudo:session): session closed for user root May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:05 volumio sudo[8071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:05 volumio sudo[8071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:05 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:05 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:05 volumio sudo[8071]: pam_unix(sudo:session): session closed for user root May 13 19:00:05 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:05 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:05 volumio volumio[1211]: info: MPD Permissions set May 13 19:00:05 volumio volumio[1211]: info: MPD Permissions set May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:05 volumio volumio[1211]: info: camilladsp spawned new process with pid 8079, instance 1, run: true May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:05 volumio volumio[1211]: info: Starting Shairport Sync May 13 19:00:05 volumio volumio[1211]: info: Starting Shairport Sync May 13 19:00:05 volumio sudo[8101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 19:00:05 volumio sudo[8101]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:05 volumio volumio[1211]: info: Preparing to generate the ALSA configuration file May 13 19:00:05 volumio sudo[8103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 19:00:05 volumio sudo[8103]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 19:00:05 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 19:00:05 volumio volumio[1211]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 13 19:00:05 volumio volumio[1211]: info: Reading ALSA contributions from plugins. May 13 19:00:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 19:00:05 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:00:05 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:05 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:00:05 volumio volumio[1211]: info: Asound.conf file unchanged, so no further update is needed May 13 19:00:05 volumio volumio[1211]: info: Output device has changed, restarting MPD May 13 19:00:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 19:00:06 volumio sudo[8101]: pam_unix(sudo:session): session closed for user root May 13 19:00:06 volumio sudo[8103]: pam_unix(sudo:session): session closed for user root May 13 19:00:06 volumio volumio[1211]: info: Output device has changed, restarting Shairport Sync May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:06 volumio sudo[8116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 19:00:06 volumio sudo[8116]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:06 volumio sudo[8118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 19:00:06 volumio sudo[8118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:06 volumio sudo[8116]: pam_unix(sudo:session): session closed for user root May 13 19:00:06 volumio volumio[1211]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 19:00:06 volumio volumio[1211]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio systemd[1]: mpd.service: Succeeded. May 13 19:00:06 volumio systemd[1]: Stopped Music Player Daemon. May 13 19:00:06 volumio systemd[1]: Starting Music Player Daemon... May 13 19:00:06 volumio volumio[1211]: info: Preparing to generate the ALSA configuration file May 13 19:00:06 volumio sudo[8141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:06 volumio sudo[8141]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:06 volumio sudo[8141]: pam_unix(sudo:session): session closed for user root May 13 19:00:06 volumio volumio[1211]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 13 19:00:06 volumio volumio[1211]: info: Reading ALSA contributions from plugins. May 13 19:00:06 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:06 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:00:06 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:06 volumio sudo[8147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:06 volumio sudo[8147]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:06 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:06 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:06 volumio volumio[1211]: info: MPD Permissions set May 13 19:00:06 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:06 volumio sudo[8147]: pam_unix(sudo:session): session closed for user root May 13 19:00:06 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:06 volumio volumio[1211]: info: Shairport-Sync Started May 13 19:00:06 volumio volumio[1211]: info: Shairport-Sync Started May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:06 volumio volumio[1211]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:06 volumio volumio[1211]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:06 volumio volumio[1211]: info: Starting Shairport Sync May 13 19:00:07 volumio sudo[8169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 19:00:07 volumio sudo[8169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:07 volumio volumio[1211]: info: Asound.conf file unchanged, so no further update is needed May 13 19:00:07 volumio volumio[1211]: info: Output device has changed, restarting MPD May 13 19:00:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 19:00:07 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 19:00:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 19:00:07 volumio sudo[8175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 13 19:00:07 volumio sudo[8175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:07 volumio sudo[8177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 19:00:07 volumio sudo[8177]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 19:00:07 volumio sudo[8177]: pam_unix(sudo:session): session closed for user root May 13 19:00:07 volumio sudo[8169]: pam_unix(sudo:session): session closed for user root May 13 19:00:07 volumio volumio[1211]: info: Output device has changed, restarting Shairport Sync May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:07 volumio systemd[1]: Started Volumio Tidal Connect Service. May 13 19:00:07 volumio sudo[8175]: pam_unix(sudo:session): session closed for user root May 13 19:00:07 volumio sudo[8187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 19:00:07 volumio sudo[8187]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 13 19:00:07 volumio systemd[1]: mpd.service: Succeeded. May 13 19:00:07 volumio systemd[1]: Stopped Music Player Daemon. May 13 19:00:07 volumio volumio[1211]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 19:00:07 volumio volumio[1211]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio systemd[1]: Starting Music Player Daemon... May 13 19:00:07 volumio sudo[8201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:07 volumio sudo[8201]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:07 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 13 19:00:07 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 13 19:00:07 volumio systemd[1]: vtcs.service: Succeeded. May 13 19:00:07 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 13 19:00:07 volumio volumio[1211]: info: CoreStateMachine::getcurrentVolume May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::volumioRetrievevolume May 13 19:00:07 volumio sudo[8206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:00:07 volumio sudo[8206]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:07 volumio sudo[8201]: pam_unix(sudo:session): session closed for user root May 13 19:00:07 volumio sudo[8206]: pam_unix(sudo:session): session closed for user root May 13 19:00:07 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:07 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:00:07 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:00:07 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:07 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:00:07 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:07 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:07 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:07 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:07 volumio volumio[1211]: info: MPD Permissions set May 13 19:00:07 volumio volumio[1211]: info: Shairport-Sync Started May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:00:07 volumio volumio[1211]: info: VolumeController:: Volume=100 Mute =false May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:07 volumio volumio[1211]: info: CoreStateMachine::pushState May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState May 13 19:00:07 volumio volumio[1211]: info: CoreStateMachine::updateTrackBlock May 13 19:00:07 volumio volumio[1211]: info: CorePlayQueue::getTrackBlock May 13 19:00:07 volumio volumio[1211]: info: CoreCommandRouter::volumioRetrievevolume May 13 19:00:07 volumio volumio[1211]: info: Starting Shairport Sync May 13 19:00:07 volumio sudo[8226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 19:00:07 volumio sudo[8226]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 19:00:08 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 19:00:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 19:00:08 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:00:08 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:00:08 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:08 volumio volumio[1211]: info: VolumeController:: Volume=100 Mute =false May 13 19:00:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:08 volumio volumio[1211]: info: CoreStateMachine::pushState May 13 19:00:08 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:00:08 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState May 13 19:00:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 19:00:08 volumio sudo[8226]: pam_unix(sudo:session): session closed for user root May 13 19:00:08 volumio volumio[1211]: info: Shairport-Sync Started May 13 19:00:08 volumio volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 13 19:00:08 volumio volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 13 19:00:08 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:08 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:08 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:08 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:08 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:08 volumio volumio[1211]: info: CoreCommandRouter::volumioNext May 13 19:00:08 volumio volumio[1211]: info: CoreStateMachine::next May 13 19:00:08 volumio volumio[1211]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 19:00:08 volumio volumio[1211]: info: ControllerMpd::next May 13 19:00:08 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand next May 13 19:00:08 volumio volumio[1211]: error: MPD error: Error: This socket has been ended by the other party May 13 19:00:08 volumio volumio[1211]: error: This socket has been ended by the other party {"code":"EPIPE"} May 13 19:00:08 volumio volumio[1211]: error: MPD error: Error: This socket has been ended by the other party May 13 19:00:08 volumio volumio[1211]: error: This socket has been ended by the other party {"code":"EPIPE"} May 13 19:00:08 volumio volumio[1211]: error: MPD error: Error: This socket has been ended by the other party May 13 19:00:08 volumio volumio[1211]: error: This socket has been ended by the other party {"code":"EPIPE"} May 13 19:00:08 volumio volumio[1211]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:08 volumio volumio[1211]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:08 volumio volumio[1211]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:08 volumio volumio[1211]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:08 volumio sudo[8261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 13 19:00:08 volumio sudo[8261]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:08 volumio sudo[8264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 13 19:00:08 volumio sudo[8264]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:08 volumio systemd[1]: Started Volumio Tidal Connect Service. May 13 19:00:08 volumio sudo[8261]: pam_unix(sudo:session): session closed for user root May 13 19:00:08 volumio sudo[8264]: pam_unix(sudo:session): session closed for user root May 13 19:00:09 volumio mpd[8195]: May 13 19:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 13 19:00:09 volumio volumio[1211]: info: Executing endpoint tc_getconfig May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 13 19:00:09 volumio vtcs[8268]: STARTING TidalConnect services, version: 1.3.0.19 May 13 19:00:09 volumio systemd[1]: Started Music Player Daemon. May 13 19:00:09 volumio sudo[8118]: pam_unix(sudo:session): session closed for user root May 13 19:00:09 volumio sudo[8049]: pam_unix(sudo:session): session closed for user root May 13 19:00:09 volumio sudo[8027]: pam_unix(sudo:session): session closed for user root May 13 19:00:09 volumio sudo[8187]: pam_unix(sudo:session): session closed for user root May 13 19:00:09 volumio vtcs[8268]: STARTED TidalConnect services. May 13 19:00:09 volumio volumio[1211]: error: MPD error: The expression evaluated to a falsy value: May 13 19:00:09 volumio volumio[1211]: assert.ok(self.idling) May 13 19:00:09 volumio volumio[1211]: error: The expression evaluated to a falsy value: May 13 19:00:09 volumio volumio[1211]: assert.ok(self.idling) May 13 19:00:09 volumio volumio[1211]: error: MPD error: The expression evaluated to a falsy value: May 13 19:00:09 volumio volumio[1211]: assert.ok(self.idling) May 13 19:00:09 volumio volumio[1211]: error: The expression evaluated to a falsy value: May 13 19:00:09 volumio volumio[1211]: assert.ok(self.idling) May 13 19:00:09 volumio volumio[1211]: error: MPD error: The expression evaluated to a falsy value: May 13 19:00:09 volumio volumio[1211]: assert.ok(self.idling) May 13 19:00:09 volumio volumio[1211]: error: The expression evaluated to a falsy value: May 13 19:00:09 volumio volumio[1211]: assert.ok(self.idling) May 13 19:00:09 volumio volumio[1211]: error: updateQueue error: null May 13 19:00:09 volumio volumio[1211]: info: Executing endpoint tc_connect May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 13 19:00:09 volumio volumio[1211]: info: Connecting to TidalConnect May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:09 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:09 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::servicePushState May 13 19:00:09 volumio volumio[1211]: info: CoreStateMachine::pushState May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState May 13 19:00:09 volumio volumio[1211]: info: CorePlayQueue::getTrack 1 May 13 19:00:09 volumio volumio[1211]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::servicePushState May 13 19:00:09 volumio volumio[1211]: info: CoreStateMachine::pushState May 13 19:00:09 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState May 13 19:00:09 volumio volumio[1211]: info: CorePlayQueue::getTrack 1 May 13 19:00:09 volumio volumio[1211]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 13 19:00:09 volumio volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 13 19:00:09 volumio volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 13 19:00:09 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:09 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:09 volumio volumio[1211]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:09 volumio volumio[1211]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:09 volumio sudo[8320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 13 19:00:09 volumio sudo[8320]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:09 volumio sudo[8320]: pam_unix(sudo:session): session closed for user root May 13 19:00:10 volumio volumio[1211]: info: TidalConnect service started! May 13 19:00:10 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:10 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:10 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:10 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:10 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:10 volumio volumio[1211]: info: TidalConnect service stoped! May 13 19:00:10 volumio volumio[1211]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:10 volumio volumio[1211]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 13 19:00:10 volumio sudo[8392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 13 19:00:10 volumio sudo[8392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:00:10 volumio sudo[8392]: pam_unix(sudo:session): session closed for user root May 13 19:00:11 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:11 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:11 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:11 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:11 volumio volumio[1211]: info: TidalConnect service started! May 13 19:00:11 volumio volumio[1211]: info: TidalConnect service started! May 13 19:00:12 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:12 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:12 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:12 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:12 volumio volumio[1211]: info: TidalConnect service started! May 13 19:00:13 volumio volumio[1211]: info: CoreCommandRouter::volumioNext May 13 19:00:13 volumio volumio[1211]: info: CoreStateMachine::next May 13 19:00:13 volumio volumio[1211]: info: ControllerMpd::next May 13 19:00:13 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand next May 13 19:00:13 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:13 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:13 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:13 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:13 volumio volumio[1211]: info: TidalConnect service started! May 13 19:00:14 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:14 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:14 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:14 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:15 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:15 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:15 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:15 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:16 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:16 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:16 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:16 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:17 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:17 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:17 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:17 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:18 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:18 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:18 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:18 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:19 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:19 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:19 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:19 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:20 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:20 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:20 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:20 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:21 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:21 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:21 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:21 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:22 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:22 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:22 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:22 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:23 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:23 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:23 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:23 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:23 volumio volumio[1211]: info: CoreCommandRouter::volumioNext May 13 19:00:23 volumio volumio[1211]: info: CoreStateMachine::next May 13 19:00:23 volumio volumio[1211]: info: ControllerMpd::next May 13 19:00:23 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand next May 13 19:00:24 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:24 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:24 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:24 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:25 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:25 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:25 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:25 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:26 volumio volumio[1211]: info: CoreCommandRouter::volumioNext May 13 19:00:26 volumio volumio[1211]: info: CoreStateMachine::next May 13 19:00:26 volumio volumio[1211]: info: ControllerMpd::next May 13 19:00:26 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand next May 13 19:00:26 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:26 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:26 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:26 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:27 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:27 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:28 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:28 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:28 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:28 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:29 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:29 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:29 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:29 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:29 volumio volumio[1211]: info: CoreCommandRouter::volumioNext May 13 19:00:29 volumio volumio[1211]: info: CoreStateMachine::next May 13 19:00:29 volumio volumio[1211]: info: ControllerMpd::next May 13 19:00:29 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand next May 13 19:00:30 volumio volumio[1211]: info: CoreCommandRouter::volumioPause May 13 19:00:30 volumio volumio[1211]: info: CoreStateMachine::pause May 13 19:00:30 volumio volumio[1211]: info: CoreStateMachine::stPlaybackTimer May 13 19:00:30 volumio volumio[1211]: info: CoreStateMachine::servicePause May 13 19:00:30 volumio volumio[1211]: info: CorePlayQueue::getTrack 1 May 13 19:00:30 volumio volumio[1211]: info: CoreCommandRouter::servicePause May 13 19:00:30 volumio volumio[1211]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 19:00:30 volumio volumio[1211]: info: ControllerMpd::pause May 13 19:00:30 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand pause May 13 19:00:30 volumio volumio[1211]: info: sendMpdCommand pause took 1 milliseconds May 13 19:00:30 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:30 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:30 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:30 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:31 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:31 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:32 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:32 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:33 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:33 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:33 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:33 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:33 volumio volumio[1211]: info: VolumeController::SetAlsaVolume54 May 13 19:00:33 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:33 volumio volumio[1211]: info: CoreStateMachine::pushState May 13 19:00:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:00:33 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState May 13 19:00:33 volumio volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 13 19:00:33 volumio volumio[1211]: SPOTIFY: SPOTIFY VOLUME 100 May 13 19:00:33 volumio volumio[1211]: SPOTIFY: VOLUMIO VOLUME 54 May 13 19:00:33 volumio volumio[1211]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 19:00:33 volumio volumio[1211]: info: Setting Spotify Volume from Volumio: 54 May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:34 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:34 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 13 19:00:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 13 19:00:35 volumio volumio[1211]: SPOTIFY: SETTING SPOTIFY VOLUME 54 May 13 19:00:35 volumio volumio[1211]: info: Sending Spotify command with payload to local API: /player/volume May 13 19:00:35 volumio go-librespot[2708]: time="2024-05-13T19:00:35+09:00" level=debug msg="update volume to 35388/65535" May 13 19:00:35 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:35 volumio go-librespot[2708]: time="2024-05-13T19:00:35+09:00" level=debug msg="put connect state because VOLUME_CHANGED" May 13 19:00:35 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:35 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:35 volumio go-librespot[2708]: time="2024-05-13T19:00:35+09:00" level=trace msg="emitting websocket event: volume" May 13 19:00:35 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:35 volumio volumio[1211]: SPOTIFY: received: {"type":"volume","data":{"value":54,"max":100}} May 13 19:00:35 volumio volumio[1211]: SPOTIFY: RECEIVED SPOTIFY VOLUME 54 May 13 19:00:36 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:36 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:36 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:36 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:37 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:37 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:37 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:37 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:38 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:38 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:38 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:38 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:39 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:39 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:39 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:39 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:40 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:40 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:40 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:40 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:41 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:41 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:41 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:41 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:42 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:42 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:42 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:42 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:43 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:43 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:43 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:43 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:44 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:44 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:44 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:44 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:45 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:45 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:45 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:45 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:46 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:46 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:46 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:46 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:47 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:47 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:47 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:47 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:48 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:48 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:48 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:48 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:49 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:49 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:49 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:49 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:50 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:50 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:50 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:50 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:51 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:51 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:51 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:51 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:52 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:52 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:52 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:52 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:53 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:53 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:53 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:53 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:54 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:54 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:54 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:54 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:55 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:55 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:55 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:55 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:56 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:56 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:56 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:56 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:57 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:57 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:58 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:58 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:58 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:58 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:00:59 volumio volumio[1211]: info: CoreCommandRouter::volumioNext May 13 19:00:59 volumio volumio[1211]: info: CoreStateMachine::next May 13 19:00:59 volumio volumio[1211]: info: ControllerMpd::next May 13 19:00:59 volumio volumio[1211]: verbose: ControllerMpd::sendMpdCommand next May 13 19:00:59 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:00:59 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:00:59 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:00:59 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:01:00 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:01:00 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:01:00 volumio volumio[1211]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:00 volumio volumio[1211]: info: Enabling I2S DAC: ST400 Dac (PCM5122) - Amp May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:00 volumio sudo[9372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 19:01:00 volumio sudo[9372]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:00 volumio sudo[9372]: pam_unix(sudo:session): session closed for user root May 13 19:01:00 volumio volumio[1211]: info: No Overlays Loaded May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:00 volumio sudo[9375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay iqaudio-dacplus May 13 19:01:00 volumio sudo[9375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/pcm5122@4c/#sound-dai-cells May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/pcm5122@4c/compatible May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/pcm5122@4c/reg May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/pcm5122@4c/AVDD-supply May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/pcm5122@4c/DVDD-supply May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/pcm5122@4c/CPVDD-supply May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/pcm5122@4c/status May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/mute-gpios May 13 19:01:00 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status May 13 19:01:00 volumio sudo[9375]: pam_unix(sudo:session): session closed for user root May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 13 19:01:00 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:00 volumio volumio[1211]: info: Found match in i2s Card Database: setting mixer Digital for card ST400 Dac (PCM5122) - Amp May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 13 19:01:01 volumio volumio[1211]: info: Updating Volume Controller Parameters: Device: 2 Name: ST400 Dac (PCM5122) - Amp Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 13 19:01:01 volumio volumio[1211]: info: Disabling external Volume Control May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 13 19:01:01 volumio volumio[1211]: info: Preparing to generate the ALSA configuration file May 13 19:01:01 volumio volumio[1211]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 13 19:01:01 volumio volumio[1211]: info: Reading ALSA contributions from plugins. May 13 19:01:01 volumio volumio[1211]: info: I2S Param [object Object] successfully enabled May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 19:01:01 volumio volumio[1211]: info: Getting Alsa Cards List without I2S DAC May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 13 19:01:01 volumio volumio[1211]: info: VolumeController:: Volume=100 Mute =false May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:01:01 volumio volumio[1211]: info: CoreStateMachine::pushState May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState May 13 19:01:01 volumio volumio[1211]: info: Asound.conf file written May 13 19:01:01 volumio sudo[9416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 13 19:01:01 volumio sudo[9416]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:01 volumio sudo[9416]: pam_unix(sudo:session): session closed for user root May 13 19:01:01 volumio volumio[1211]: info: Output device has changed, restarting MPD May 13 19:01:01 volumio sudo[9425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 19:01:01 volumio sudo[9425]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:01 volumio sudo[9425]: pam_unix(sudo:session): session closed for user root May 13 19:01:01 volumio volumio[1211]: info: Output device has changed, restarting Shairport Sync May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:01 volumio sudo[9430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 19:01:01 volumio sudo[9430]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:01 volumio systemd[1]: Stopping Music Player Daemon... May 13 19:01:01 volumio volumio[1211]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 19:01:01 volumio volumio[1211]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 19:01:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio vtcs[8268]: [2024-05-13 19:01:02.037] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected May 13 19:01:02 volumio vtcs[8268]: [2024-05-13 19:01:02.037] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 13 19:01:02 volumio systemd[1]: mpd.service: Succeeded. May 13 19:01:02 volumio systemd[1]: Stopped Music Player Daemon. May 13 19:01:02 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 13 19:01:02 volumio volumio[1211]: info: Output device has changed, restarting MPD May 13 19:01:02 volumio sudo[9443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:01:02 volumio sudo[9443]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio systemd[1]: Starting Music Player Daemon... May 13 19:01:02 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 13 19:01:02 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 13 19:01:02 volumio systemd[1]: vtcs.service: Succeeded. May 13 19:01:02 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 13 19:01:02 volumio sudo[9443]: pam_unix(sudo:session): session closed for user root May 13 19:01:02 volumio sudo[9446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 19:01:02 volumio sudo[9446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio sudo[9446]: pam_unix(sudo:session): session closed for user root May 13 19:01:02 volumio volumio[1211]: info: Output device has changed, restarting Shairport Sync May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:02 volumio sudo[9452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 19:01:02 volumio sudo[9452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 13 19:01:02 volumio systemd[1]: mpd.service: Succeeded. May 13 19:01:02 volumio systemd[1]: Stopped Music Player Daemon. May 13 19:01:02 volumio volumio[1211]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 19:01:02 volumio volumio[1211]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio systemd[1]: Starting Music Player Daemon... May 13 19:01:02 volumio sudo[9463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:01:02 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 13 19:01:02 volumio sudo[9463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio sudo[9468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:01:02 volumio sudo[9468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio sudo[9463]: pam_unix(sudo:session): session closed for user root May 13 19:01:02 volumio sudo[9468]: pam_unix(sudo:session): session closed for user root May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:01:02 volumio sudo[9474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue May 13 19:01:02 volumio volumio[1211]: info: CoreStateMachine::getQueue May 13 19:01:02 volumio volumio[1211]: info: CorePlayQueue::getQueue May 13 19:01:02 volumio sudo[9474]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 13 19:01:02 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running May 13 19:01:02 volumio sudo[9474]: pam_unix(sudo:session): session closed for user root May 13 19:01:02 volumio volumio[1211]: info: MPD Permissions set May 13 19:01:02 volumio volumio[1211]: info: MPD Permissions set May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState May 13 19:01:02 volumio volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 13 19:01:02 volumio volumio[1211]: SPOTIFY: SPOTIFY VOLUME 54 May 13 19:01:02 volumio volumio[1211]: SPOTIFY: VOLUMIO VOLUME 100 May 13 19:01:02 volumio volumio[1211]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 19:01:02 volumio volumio[1211]: info: Setting Spotify Volume from Volumio: 100 May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 19:01:02 volumio volumio[1211]: info: Starting Shairport Sync May 13 19:01:02 volumio volumio[1211]: info: Starting Shairport Sync May 13 19:01:02 volumio sudo[9492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 19:01:02 volumio sudo[9492]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio volumio[1211]: info: Preparing to generate the ALSA configuration file May 13 19:01:02 volumio sudo[9497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 19:01:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 19:01:02 volumio sudo[9497]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:01:02 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 19:01:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 19:01:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 19:01:02 volumio volumio[1211]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 13 19:01:02 volumio volumio[1211]: info: Reading ALSA contributions from plugins. May 13 19:01:02 volumio sudo[9492]: pam_unix(sudo:session): session closed for user root May 13 19:01:02 volumio volumio[1211]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 13 19:01:02 volumio volumio[1211]: info: PLUGIN onReboot : networkfs May 13 19:01:02 volumio volumio[1211]: info: PLUGIN onReboot : audiophonicsonoff May 13 19:01:03 volumio volumio[1211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 19:01:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 19:01:03 volumio systemd[1]: shairport-sync.service: Succeeded. May 13 19:01:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 19:01:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 19:01:03 volumio volumio[1211]: TypeError: Cannot read property 'writeSync' of undefined May 13 19:01:03 volumio volumio[1211]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) May 13 19:01:03 volumio volumio[1211]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 13 19:01:03 volumio volumio[1211]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 13 19:01:03 volumio volumio[1211]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 13 19:01:03 volumio volumio[1211]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 13 19:01:03 volumio volumio[1211]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 13 19:01:03 volumio volumio[1211]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) May 13 19:01:03 volumio volumio[1211]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) May 13 19:01:03 volumio volumio[1211]: at Socket.emit (events.js:315:20) May 13 19:01:03 volumio volumio[1211]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 13 19:01:03 volumio volumio[1211]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 13 19:01:03 volumio volumio[1211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 19:01:03 volumio sudo[9497]: pam_unix(sudo:session): session closed for user root May 13 19:01:03 volumio mpd[9459]: May 13 19:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 13 19:01:03 volumio systemd[1]: Started Music Player Daemon. May 13 19:01:03 volumio sudo[9430]: pam_unix(sudo:session): session closed for user root May 13 19:01:03 volumio sudo[9452]: pam_unix(sudo:session): session closed for user root May 13 19:01:04 volumio sudo[9549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-13 19:00 May 13 19:01:04 volumio sudo[9549]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"