-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2024-07-10 21:43:06 CEST. -- Jul 10 21:42:01 home-volumio volumio[1178]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jul 10 21:42:01 home-volumio volumio[1178]: info: Restoring Previous Volume level: 100 false true Jul 10 21:42:01 home-volumio volumio[1178]: info: VolumeController::SetAlsaVolume100 Jul 10 21:42:01 home-volumio volumio[1178]: info: Enable softmixer device for audio device number 1 Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::volumioStop Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::stop Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::updateTrackBlock Jul 10 21:42:01 home-volumio volumio[1178]: info: CorePlayQueue::getTrackBlock Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:01 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::serviceStop Jul 10 21:42:01 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::serviceStop Jul 10 21:42:01 home-volumio volumio[1178]: info: [1720640521063] ControllerWebradio::stop Jul 10 21:42:01 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:42:01 home-volumio volumio[1178]: info: Enable softmixer device for audio device undefined Jul 10 21:42:01 home-volumio volumio[1178]: info: Output device has changed, restarting MPD Jul 10 21:42:01 home-volumio sudo[2630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 21:42:01 home-volumio sudo[2630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio volumio[1178]: info: Output device has changed, restarting Shairport Sync Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:01 home-volumio sudo[2630]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio sudo[2633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 21:42:01 home-volumio sudo[2633]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio systemd[1]: Stopping Music Player Daemon... Jul 10 21:42:01 home-volumio volumio[1178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio systemd[1]: mpd.service: Succeeded. Jul 10 21:42:01 home-volumio systemd[1]: Stopped Music Player Daemon. Jul 10 21:42:01 home-volumio vtcs[2579]: [2024-07-10 21:42:01.128] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jul 10 21:42:01 home-volumio vtcs[2579]: [2024-07-10 21:42:01.129] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=5 errorno=104 error=Connection reset by peer Jul 10 21:42:01 home-volumio vtcs[2579]: [2024-07-10 21:42:01.129] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jul 10 21:42:01 home-volumio volumio[1178]: info: Volume configurations have been set Jul 10 21:42:01 home-volumio systemd[1]: Starting Music Player Daemon... Jul 10 21:42:01 home-volumio sudo[2642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:01 home-volumio sudo[2642]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio sudo[2645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:01 home-volumio sudo[2645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jul 10 21:42:01 home-volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 10 21:42:01 home-volumio systemd[1]: vtcs.service: Succeeded. Jul 10 21:42:01 home-volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jul 10 21:42:01 home-volumio sudo[2642]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio sudo[2638]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 21:42:01 home-volumio sudo[2638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 10 21:42:01 home-volumio sudo[2645]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio volumio[1178]: info: Updating Volume Controller Parameters: Device: 1 Name: Pisound Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 10 21:42:01 home-volumio volumio[1178]: info: Disabling external Volume Control Jul 10 21:42:01 home-volumio sudo[2638]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:01 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:01 home-volumio volumio[1178]: error: MPD error: Error: write EPIPE Jul 10 21:42:01 home-volumio volumio[1178]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 10 21:42:01 home-volumio sudo[2660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:01 home-volumio sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio sudo[2660]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:01 home-volumio sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio sudo[2663]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:01 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:01 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:01 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:01 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:01 home-volumio volumio[1178]: info: MPD Permissions set Jul 10 21:42:01 home-volumio volumio[1178]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 10 21:42:01 home-volumio volumio[1178]: info: Software Volume ALSA configuration written Jul 10 21:42:01 home-volumio volumio[1178]: info: Preparing to generate the ALSA configuration file Jul 10 21:42:01 home-volumio volumio[1178]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 10 21:42:01 home-volumio volumio[1178]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 10 21:42:01 home-volumio volumio[1178]: info: Reading ALSA contributions from plugins. Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 21:42:01 home-volumio volumio[1178]: info: Getting Alsa Cards List without I2S DAC Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 21:42:01 home-volumio volumio[1178]: info: Starting Shairport Sync Jul 10 21:42:01 home-volumio volumio[1178]: info: Asound.conf file written Jul 10 21:42:01 home-volumio mpd[2652]: Jul 10 21:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 21:42:01 home-volumio systemd[1]: Started Music Player Daemon. Jul 10 21:42:01 home-volumio sudo[2633]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 21:42:01 home-volumio sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 21:42:01 home-volumio systemd[1]: shairport-sync.service: Succeeded. Jul 10 21:42:01 home-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 21:42:01 home-volumio sudo[2688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 10 21:42:01 home-volumio sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio sudo[2688]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 21:42:01 home-volumio sudo[2683]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio volumio[1178]: info: Output device has changed, restarting MPD Jul 10 21:42:01 home-volumio sudo[2696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 21:42:01 home-volumio sudo[2696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio sudo[2696]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio volumio[1178]: info: Output device has changed, restarting Shairport Sync Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:01 home-volumio sudo[2699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 21:42:01 home-volumio sudo[2699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio systemd[1]: Stopping Music Player Daemon... Jul 10 21:42:01 home-volumio volumio[1178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 21:42:01 home-volumio volumio[1178]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 21:42:01 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:01 home-volumio systemd[1]: mpd.service: Succeeded. Jul 10 21:42:01 home-volumio systemd[1]: Stopped Music Player Daemon. Jul 10 21:42:01 home-volumio systemd[1]: Starting Music Player Daemon... Jul 10 21:42:01 home-volumio sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:01 home-volumio sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio sudo[2708]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio sudo[2704]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 21:42:01 home-volumio sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:01 home-volumio sudo[2704]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:01 home-volumio mpd[2714]: Jul 10 21:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 21:42:01 home-volumio systemd[1]: Started Music Player Daemon. Jul 10 21:42:01 home-volumio sudo[2699]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 10 21:42:02 home-volumio volumio[1178]: info: Output device has changed, restarting MPD Jul 10 21:42:02 home-volumio sudo[2720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:02 home-volumio sudo[2720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio sudo[2723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 21:42:02 home-volumio sudo[2723]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio volumio[1178]: info: Output device has changed, restarting Shairport Sync Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:02 home-volumio sudo[2723]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio sudo[2720]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio sudo[2726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 21:42:02 home-volumio sudo[2726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio systemd[1]: Stopping Music Player Daemon... Jul 10 21:42:02 home-volumio systemd[1]: mpd.service: Succeeded. Jul 10 21:42:02 home-volumio systemd[1]: Stopped Music Player Daemon. Jul 10 21:42:02 home-volumio volumio[1178]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 21:42:02 home-volumio volumio[1178]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio systemd[1]: Starting Music Player Daemon... Jul 10 21:42:02 home-volumio sudo[2736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:02 home-volumio sudo[2736]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio sudo[2732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 21:42:02 home-volumio sudo[2732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio sudo[2732]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 10 21:42:02 home-volumio volumio[1178]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 10 21:42:02 home-volumio sudo[2736]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 10 21:42:02 home-volumio volumio[1178]: info: Disabling external Volume Control Jul 10 21:42:02 home-volumio sudo[2751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:02 home-volumio sudo[2751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio sudo[2751]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio sudo[2754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:02 home-volumio sudo[2754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:02 home-volumio sudo[2757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 21:42:02 home-volumio sudo[2757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:02 home-volumio sudo[2757]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:02 home-volumio sudo[2754]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:02 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:02 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:02 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:02 home-volumio volumio[1178]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 10 21:42:02 home-volumio volumio[1178]: info: MPD Permissions set Jul 10 21:42:02 home-volumio volumio[1178]: info: MPD Permissions set Jul 10 21:42:02 home-volumio volumio[1178]: info: Shairport-Sync Started Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:02 home-volumio volumio[1178]: info: Starting Shairport Sync Jul 10 21:42:02 home-volumio volumio[1178]: info: Starting Shairport Sync Jul 10 21:42:02 home-volumio sudo[2771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 21:42:02 home-volumio sudo[2771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:02 home-volumio systemd[1]: shairport-sync.service: Succeeded. Jul 10 21:42:02 home-volumio sudo[2774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 21:42:02 home-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 21:42:02 home-volumio sudo[2774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:02 home-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 21:42:02 home-volumio sudo[2771]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: info: Shairport-Sync Started Jul 10 21:42:02 home-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 21:42:02 home-volumio systemd[1]: shairport-sync.service: Succeeded. Jul 10 21:42:02 home-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:02 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:02 home-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 21:42:02 home-volumio sudo[2774]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: info: Shairport-Sync Started Jul 10 21:42:02 home-volumio mpd[2741]: Jul 10 21:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 21:42:02 home-volumio systemd[1]: Started Music Player Daemon. Jul 10 21:42:02 home-volumio sudo[2726]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:02 home-volumio volumio[1178]: error: MPD error: The expression evaluated to a falsy value: Jul 10 21:42:02 home-volumio volumio[1178]: assert.ok(self.idling) Jul 10 21:42:02 home-volumio volumio[1178]: error: The expression evaluated to a falsy value: Jul 10 21:42:02 home-volumio volumio[1178]: assert.ok(self.idling) Jul 10 21:42:02 home-volumio volumio[1178]: error: updateQueue error: null Jul 10 21:42:02 home-volumio volumio[1178]: error: updateQueue error: null Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 10 21:42:04 home-volumio sudo[2786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 21:42:04 home-volumio sudo[2786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:04 home-volumio systemd[1]: Stopping Music Player Daemon... Jul 10 21:42:04 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:04 home-volumio systemd[1]: mpd.service: Succeeded. Jul 10 21:42:04 home-volumio systemd[1]: Stopped Music Player Daemon. Jul 10 21:42:04 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 21:42:04 home-volumio systemd[1]: Starting Music Player Daemon... Jul 10 21:42:04 home-volumio volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 21:42:04 home-volumio sudo[2788]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 21:42:04 home-volumio sudo[2788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:04 home-volumio sudo[2788]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:04 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:04 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:04 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:04 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:04 home-volumio volumio[1178]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:04 home-volumio volumio[1178]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:04 home-volumio volumio[1178]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:04 home-volumio volumio[1178]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:04 home-volumio sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 21:42:04 home-volumio sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:04 home-volumio sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 21:42:04 home-volumio sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:04 home-volumio systemd[1]: Started Volumio Tidal Connect Service. Jul 10 21:42:04 home-volumio sudo[2808]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:04 home-volumio sudo[2811]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:04 home-volumio mpd[2790]: Jul 10 21:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 21:42:04 home-volumio systemd[1]: Started Music Player Daemon. Jul 10 21:42:04 home-volumio sudo[2786]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:04 home-volumio volumio[1178]: error: updateQueue error: null Jul 10 21:42:04 home-volumio volumio[1178]: info: Executing endpoint tc_getconfig Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 10 21:42:04 home-volumio vtcs[2812]: STARTING TidalConnect services, version: 1.4.0.34 Jul 10 21:42:04 home-volumio vtcs[2812]: STARTED TidalConnect services. Jul 10 21:42:04 home-volumio volumio[1178]: info: Executing endpoint tc_connect Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 10 21:42:04 home-volumio volumio[1178]: info: Connecting to TidalConnect Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:04 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:04 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:04 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:04 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:04 home-volumio volumio[1178]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:04 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:04 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:04 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:04 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:04 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:04 home-volumio volumio[1178]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jul 10 21:42:04 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:05 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:05 home-volumio volumio[1178]: info: VolumeController::SetAlsaVolume100 Jul 10 21:42:05 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:05 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:05 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:05 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:05 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:05 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:05 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:05 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:05 home-volumio volumio[1178]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:05 home-volumio volumio[1178]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:05 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:05 home-volumio sudo[2845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 21:42:05 home-volumio sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:05 home-volumio volumio[1178]: info: TidalConnect service stoped! Jul 10 21:42:05 home-volumio sudo[2845]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:05 home-volumio volumio[1178]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:05 home-volumio volumio[1178]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:05 home-volumio volumio[1178]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:05 home-volumio volumio[1178]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 21:42:05 home-volumio sudo[2865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 21:42:05 home-volumio sudo[2865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:05 home-volumio sudo[2868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 21:42:05 home-volumio sudo[2868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 21:42:05 home-volumio sudo[2865]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:05 home-volumio sudo[2868]: pam_unix(sudo:session): session closed for user root Jul 10 21:42:05 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPlay Jul 10 21:42:05 home-volumio volumio[1178]: info: CoreStateMachine::play index undefined Jul 10 21:42:05 home-volumio volumio[1178]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:42:05 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:05 home-volumio volumio[1178]: info: CoreStateMachine::startPlaybackTimer Jul 10 21:42:05 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:05 home-volumio volumio[1178]: info: [1720640525816] ControllerWebradio::clearAddPlayTrack Jul 10 21:42:05 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:42:05 home-volumio volumio[1178]: info: sendMpdCommand stop took 0 milliseconds Jul 10 21:42:05 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 21:42:05 home-volumio volumio[1178]: info: Jul 10 21:42:05 home-volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jul 10 21:42:05 home-volumio volumio[1178]: info: Ignoring MPD Status Update Jul 10 21:42:05 home-volumio volumio[1178]: info: sendMpdCommand clear took 1 milliseconds Jul 10 21:42:05 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand load "http://streams.fluxfm.de/80er/mp3-320/tunein/" Jul 10 21:42:05 home-volumio volumio[1178]: error: updateQueue error: null Jul 10 21:42:05 home-volumio volumio[1178]: info: ------------------------------ 2ms Jul 10 21:42:06 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 21:42:07 home-volumio volumio[1178]: info: TidalConnect service started! Jul 10 21:42:07 home-volumio volumio[1178]: info: TidalConnect service started! Jul 10 21:42:08 home-volumio volumio[1178]: info: TidalConnect service started! Jul 10 21:42:08 home-volumio volumio[1178]: info: TidalConnect service started! Jul 10 21:42:08 home-volumio volumio[1178]: info: TidalConnect service started! Jul 10 21:42:09 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand add "http://streams.fluxfm.de/80er/mp3-320/tunein/" Jul 10 21:42:09 home-volumio volumio[1178]: info: Jul 10 21:42:09 home-volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jul 10 21:42:09 home-volumio volumio[1178]: info: Ignoring MPD Status Update Jul 10 21:42:09 home-volumio volumio[1178]: info: sendMpdCommand add "http://streams.fluxfm.de/80er/mp3-320/tunein/" took 0 milliseconds Jul 10 21:42:09 home-volumio volumio[1178]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 10 21:42:09 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand play Jul 10 21:42:09 home-volumio volumio[1178]: info: ------------------------------ 1ms Jul 10 21:42:09 home-volumio volumio[1178]: info: sendMpdCommand play took 1 milliseconds Jul 10 21:42:11 home-volumio volumio[1178]: info: VolumeController::SetAlsaVolume40 Jul 10 21:42:11 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:11 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:11 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:11 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:11 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:12 home-volumio volumio[1178]: info: Jul 10 21:42:12 home-volumio volumio[1178]: ---------------------------- MPD announces state update: player Jul 10 21:42:12 home-volumio volumio[1178]: info: ControllerMpd::getState Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:42:12 home-volumio volumio[1178]: info: Jul 10 21:42:12 home-volumio volumio[1178]: ---------------------------- MPD announces state update: player Jul 10 21:42:12 home-volumio volumio[1178]: info: sendMpdCommand status took 10 milliseconds Jul 10 21:42:12 home-volumio volumio[1178]: info: ControllerMpd::getState Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::parseState Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:42:12 home-volumio volumio[1178]: info: sendMpdCommand status took 1 milliseconds Jul 10 21:42:12 home-volumio volumio[1178]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::parseState Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:42:12 home-volumio volumio[1178]: info: ControllerMpd::pushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:12 home-volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.fluxfm.de/80er/mp3-320/tunein/","trackType":""} Jul 10 21:42:12 home-volumio volumio[1178]: verbose: CURRENT POSITION 16 Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:42:12 home-volumio volumio[1178]: info: ------------------------------ 16ms Jul 10 21:42:12 home-volumio volumio[1178]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:42:12 home-volumio volumio[1178]: info: ControllerMpd::pushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:12 home-volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.fluxfm.de/80er/mp3-320/tunein/","trackType":""} Jul 10 21:42:12 home-volumio volumio[1178]: verbose: CURRENT POSITION 16 Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:42:12 home-volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:12 home-volumio volumio[1178]: info: ------------------------------ 34ms Jul 10 21:42:12 home-volumio volumio[1178]: info: Jul 10 21:42:12 home-volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jul 10 21:42:12 home-volumio volumio[1178]: info: Ignoring MPD Status Update Jul 10 21:42:12 home-volumio volumio[1178]: info: Jul 10 21:42:12 home-volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jul 10 21:42:12 home-volumio volumio[1178]: info: Ignoring MPD Status Update Jul 10 21:42:12 home-volumio volumio[1178]: info: Jul 10 21:42:12 home-volumio volumio[1178]: ---------------------------- MPD announces state update: player Jul 10 21:42:12 home-volumio volumio[1178]: info: ControllerMpd::getState Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:42:12 home-volumio volumio[1178]: info: ------------------------------ 1ms Jul 10 21:42:12 home-volumio volumio[1178]: info: ------------------------------ 0ms Jul 10 21:42:12 home-volumio volumio[1178]: info: sendMpdCommand status took 0 milliseconds Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::parseState Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:42:12 home-volumio volumio[1178]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 10 21:42:12 home-volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:42:12 home-volumio volumio[1178]: info: ControllerMpd::pushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:12 home-volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1057,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"FluxFM - 80er","artist":"FluxFM - 80er","album":null,"uri":"http://streams.fluxfm.de/80er/mp3-320/tunein/","trackType":""} Jul 10 21:42:12 home-volumio volumio[1178]: verbose: CURRENT POSITION 16 Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:42:12 home-volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:12 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:12 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:12 home-volumio volumio[1178]: info: ------------------------------ 21ms Jul 10 21:42:13 home-volumio volumio[1178]: info: VolumeController::SetAlsaVolume55 Jul 10 21:42:13 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:13 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:13 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:13 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:13 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:14 home-volumio volumio[1178]: info: VolumeController::SetAlsaVolume50 Jul 10 21:42:14 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:14 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:14 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:14 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:14 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:17 home-volumio volumio[1178]: info: Jul 10 21:42:17 home-volumio volumio[1178]: ---------------------------- MPD announces system playlist update Jul 10 21:42:17 home-volumio volumio[1178]: info: Ignoring MPD Status Update Jul 10 21:42:17 home-volumio volumio[1178]: info: Jul 10 21:42:17 home-volumio volumio[1178]: ---------------------------- MPD announces state update: player Jul 10 21:42:17 home-volumio volumio[1178]: info: ControllerMpd::getState Jul 10 21:42:17 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:42:17 home-volumio volumio[1178]: info: ------------------------------ 1ms Jul 10 21:42:17 home-volumio volumio[1178]: info: sendMpdCommand status took 1 milliseconds Jul 10 21:42:17 home-volumio volumio[1178]: verbose: ControllerMpd::parseState Jul 10 21:42:17 home-volumio volumio[1178]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:42:17 home-volumio volumio[1178]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 10 21:42:17 home-volumio volumio[1178]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:42:17 home-volumio volumio[1178]: info: ControllerMpd::pushState Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreCommandRouter::servicePushState Jul 10 21:42:17 home-volumio volumio[1178]: info: CorePlayQueue::getTrack 16 Jul 10 21:42:17 home-volumio volumio[1178]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dexy's Midnight Runners - Come on Eileen","artist":"FluxFM - 80er","album":null,"uri":"http://streams.fluxfm.de/80er/mp3-320/tunein/","trackType":""} Jul 10 21:42:17 home-volumio volumio[1178]: verbose: CURRENT POSITION 16 Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreStateMachine::syncState stateService play Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:42:17 home-volumio volumio[1178]: info: Received an update from plugin. extracting info from payload Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:17 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:17 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreStateMachine::pushState Jul 10 21:42:17 home-volumio volumio[1178]: info: CoreCommandRouter::volumioPushState Jul 10 21:42:17 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Jul 10 21:42:17 home-volumio volumio[1178]: info: MRS: Pushing multiroomSync output Jul 10 21:42:17 home-volumio volumio[1178]: info: ------------------------------ 28ms Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 21:42:19 home-volumio volumio[1178]: info: Getting Alsa Cards List without I2S DAC Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 10 21:42:19 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 21:42:27 home-volumio volumiologrotate[928]: ls: cannot access '/var/log/samba/log.wb-HOME': No such file or directory Jul 10 21:42:27 home-volumio volumiologrotate[928]: ls: cannot access 'VOLUMIO': No such file or directory Jul 10 21:42:55 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 21:42:55 home-volumio volumio[1178]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 10 21:42:57 home-volumio volumio[1178]: info: Enabling plugin audiophonicsonoff Jul 10 21:42:57 home-volumio volumio[1178]: info: Loading plugin "audiophonicsonoff"... Jul 10 21:42:57 home-volumio volumio[1178]: info: Applying required configuration parameters for plugin audiophonicsonoff Jul 10 21:42:57 home-volumio volumio[1178]: info: Audiophonics on/off initiated Jul 10 21:42:57 home-volumio volumio[1178]: info: PLUGIN START: audiophonicsonoff Jul 10 21:42:57 home-volumio volumio[1178]: info: Configuring GPIO pins Jul 10 21:42:57 home-volumio volumio[1178]: info: Error: Error: EINVAL: invalid argument, write Jul 10 21:42:57 home-volumio kernel: export_store: invalid GPIO 27 Jul 10 21:43:05 home-volumio volumio[1178]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 10 21:43:05 home-volumio volumio[1178]: info: PLUGIN onReboot : networkfs Jul 10 21:43:05 home-volumio volumio[1178]: info: PLUGIN onReboot : audiophonicsonoff Jul 10 21:43:05 home-volumio volumio[1178]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 21:43:05 home-volumio volumio[1178]: TypeError: Cannot read property 'writeSync' of undefined Jul 10 21:43:05 home-volumio volumio[1178]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jul 10 21:43:05 home-volumio volumio[1178]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 10 21:43:05 home-volumio volumio[1178]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 10 21:43:05 home-volumio volumio[1178]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 10 21:43:05 home-volumio volumio[1178]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 10 21:43:05 home-volumio volumio[1178]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 10 21:43:05 home-volumio volumio[1178]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Jul 10 21:43:05 home-volumio volumio[1178]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:860:33) Jul 10 21:43:05 home-volumio volumio[1178]: at Socket.emit (events.js:315:20) Jul 10 21:43:05 home-volumio volumio[1178]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 10 21:43:05 home-volumio volumio[1178]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 10 21:43:05 home-volumio volumio[1178]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 21:43:06 home-volumio sudo[2945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 21:42 Jul 10 21:43:06 home-volumio sudo[2945]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="905ee02fb5387537cfa16b84ebd7f14b7640848e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 09 Jul 2024 05:46:05 PM CEST" VOLUMIO_VERSION="3.735" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="dda52bc4d96a3b2a108175dfbb2b5844"