-- Logs begin at Wed 2024-07-10 04:40:52 -07, end at Wed 2024-07-10 04:47:30 -07. -- Jul 10 04:46:02 volumiothaison volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 04:46:03 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:03 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 04:46:03 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:03 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 10 04:46:03 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 04:46:08 volumiothaison volumio[1246]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:46:08 volumiothaison volumio[1246]: info: Restoring Previous Volume level: 100 false true Jul 10 04:46:08 volumiothaison volumio[1246]: info: VolumeController::SetAlsaVolume100 Jul 10 04:46:08 volumiothaison volumio[1246]: info: Enable softmixer device for audio device number 5 Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioStop Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreStateMachine::stop Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreStateMachine::stPlaybackTimer Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreStateMachine::updateTrackBlock Jul 10 04:46:08 volumiothaison volumio[1246]: info: CorePlayQueue::getTrackBlock Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:08 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:08 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:08 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreStateMachine::serviceStop Jul 10 04:46:08 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::serviceStop Jul 10 04:46:08 volumiothaison volumio[1246]: info: [1720611968963] ControllerUPNPBrowser::stop Jul 10 04:46:08 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 04:46:08 volumiothaison volumio[1246]: info: Enable softmixer device for audio device undefined Jul 10 04:46:08 volumiothaison volumio[1246]: info: Output device has changed, restarting MPD Jul 10 04:46:08 volumiothaison sudo[3219]: volumio : unable to resolve host volumiothaison Jul 10 04:46:08 volumiothaison volumio[1246]: info: Output device has changed, restarting Shairport Sync Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:08 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:08 volumiothaison sudo[3219]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:08 volumiothaison sudo[3219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 04:46:08 volumiothaison sudo[3219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:08 volumiothaison sudo[3219]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:08 volumiothaison sudo[3221]: volumio : unable to resolve host volumiothaison Jul 10 04:46:08 volumiothaison sudo[3221]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:08 volumiothaison sudo[3221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 04:46:08 volumiothaison sudo[3221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:08 volumiothaison systemd[1]: Stopping Music Player Daemon... Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison systemd[1]: mpd.service: Succeeded. Jul 10 04:46:09 volumiothaison systemd[1]: Stopped Music Player Daemon. Jul 10 04:46:09 volumiothaison vtcs[2862]: [2024-07-10 04:46:09.019] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jul 10 04:46:09 volumiothaison vtcs[2862]: [2024-07-10 04:46:09.019] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Jul 10 04:46:09 volumiothaison vtcs[2862]: [2024-07-10 04:46:09.019] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jul 10 04:46:09 volumiothaison systemd[1]: Starting Music Player Daemon... Jul 10 04:46:09 volumiothaison volumio[1246]: info: Volume configurations have been set Jul 10 04:46:09 volumiothaison sudo[3233]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3233]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:09 volumiothaison sudo[3233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison sudo[3230]: root : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3230]: sudo: unable to resolve host volumiothaison: System error Jul 10 04:46:09 volumiothaison sudo[3230]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3230]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 04:46:09 volumiothaison sudo[3230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison sudo[3230]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison systemd[1]: Stopping Volumio Tidal Connect Service... Jul 10 04:46:09 volumiothaison systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 10 04:46:09 volumiothaison systemd[1]: vtcs.service: Succeeded. Jul 10 04:46:09 volumiothaison systemd[1]: Stopped Volumio Tidal Connect Service. Jul 10 04:46:09 volumiothaison sudo[3236]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3236]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:09 volumiothaison sudo[3236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison sudo[3236]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison sudo[3233]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 10 04:46:09 volumiothaison volumio[1246]: info: Updating Volume Controller Parameters: Device: 5 Name: SB X-Fi Surround 5.1 Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 10 04:46:09 volumiothaison volumio[1246]: info: Disabling external Volume Control Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:46:09 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:09 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:09 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:09 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:09 volumiothaison volumio[1246]: error: MPD error: Error: write EPIPE Jul 10 04:46:09 volumiothaison volumio[1246]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 10 04:46:09 volumiothaison sudo[3252]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3252]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:09 volumiothaison sudo[3252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison sudo[3255]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3255]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:09 volumiothaison sudo[3255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:09 volumiothaison sudo[3255]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:09 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:09 volumiothaison sudo[3252]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:46:09 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:09 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:09 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:09 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:09 volumiothaison volumio[1246]: info: MPD Permissions set Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:46:09 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:09 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:09 volumiothaison volumio[1246]: SPOTIFY: SPOTIFY VOLUME undefined Jul 10 04:46:09 volumiothaison volumio[1246]: SPOTIFY: VOLUMIO VOLUME 100 Jul 10 04:46:09 volumiothaison volumio[1246]: info: Aligning Spotify Volume to Volumio Volume Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:46:09 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:09 volumiothaison volumio[1246]: info: Setting Spotify Volume from Volumio: 100 Jul 10 04:46:09 volumiothaison volumio[1246]: info: Software Volume ALSA configuration written Jul 10 04:46:09 volumiothaison volumio[1246]: info: Preparing to generate the ALSA configuration file Jul 10 04:46:09 volumiothaison volumio[1246]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 10 04:46:09 volumiothaison volumio[1246]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 10 04:46:09 volumiothaison volumio[1246]: info: Reading ALSA contributions from plugins. Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison mpd[3239]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 04:46:09 volumiothaison mpd[3239]: output: No 'audio_output' defined in config file Jul 10 04:46:09 volumiothaison mpd[3239]: couldn't open play stream: No such file or directory Jul 10 04:46:09 volumiothaison mpd[3239]: sndio_output: Error opening default sndio device Jul 10 04:46:09 volumiothaison mpd[3239]: output: Successfully detected a alsa audio device Jul 10 04:46:09 volumiothaison mpd[3239]: zeroconf: No global port, disabling zeroconf Jul 10 04:46:09 volumiothaison systemd[1]: Started Music Player Daemon. Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 04:46:09 volumiothaison sudo[3221]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 04:46:09 volumiothaison volumio[1246]: info: Starting Shairport Sync Jul 10 04:46:09 volumiothaison volumio[1246]: error: updateQueue error: null Jul 10 04:46:09 volumiothaison volumio[1246]: info: Asound.conf file written Jul 10 04:46:09 volumiothaison sudo[3272]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3272]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 04:46:09 volumiothaison sudo[3272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 04:46:09 volumiothaison systemd[1]: shairport-sync.service: Succeeded. Jul 10 04:46:09 volumiothaison systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 04:46:09 volumiothaison sudo[3275]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3275]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 04:46:09 volumiothaison volumio[1246]: sudo: unable to resolve host volumiothaison: System error Jul 10 04:46:09 volumiothaison sudo[3275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 10 04:46:09 volumiothaison sudo[3275]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison sudo[3275]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison sudo[3272]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: Output device has changed, restarting MPD Jul 10 04:46:09 volumiothaison sudo[3283]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3283]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 04:46:09 volumiothaison sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison volumio[1246]: info: Output device has changed, restarting Shairport Sync Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:09 volumiothaison sudo[3283]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison sudo[3285]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3285]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 04:46:09 volumiothaison sudo[3285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison systemd[1]: Stopping Music Player Daemon... Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 04:46:09 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:09 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:09 volumiothaison volumio[1246]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 04:46:09 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:09 volumiothaison systemd[1]: mpd.service: Succeeded. Jul 10 04:46:09 volumiothaison systemd[1]: Stopped Music Player Daemon. Jul 10 04:46:09 volumiothaison systemd[1]: Starting Music Player Daemon... Jul 10 04:46:09 volumiothaison sudo[3295]: volumio : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3295]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:09 volumiothaison sudo[3295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison sudo[3291]: root : unable to resolve host volumiothaison Jul 10 04:46:09 volumiothaison sudo[3291]: sudo: unable to resolve host volumiothaison: System error Jul 10 04:46:09 volumiothaison sudo[3295]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison sudo[3291]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:09 volumiothaison sudo[3291]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 04:46:09 volumiothaison sudo[3291]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:09 volumiothaison sudo[3291]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:09 volumiothaison mpd[3301]: Jul 10 04:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 04:46:09 volumiothaison systemd[1]: Started Music Player Daemon. Jul 10 04:46:09 volumiothaison sudo[3285]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison systemd[1]: Stopping Network Time Service... Jul 10 04:46:10 volumiothaison ntpd[1237]: ntpd exiting on signal 15 (Terminated) Jul 10 04:46:10 volumiothaison systemd[1]: ntp.service: Succeeded. Jul 10 04:46:10 volumiothaison systemd[1]: Stopped Network Time Service. Jul 10 04:46:10 volumiothaison systemd[1]: Starting Network Time Service... Jul 10 04:46:10 volumiothaison ntpd[3335]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 10 04:46:10 volumiothaison ntpd[3335]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 10 04:46:10 volumiothaison systemd[1]: Started Network Time Service. Jul 10 04:46:10 volumiothaison ntpd[3341]: proto: precision = 0.052 usec (-24) Jul 10 04:46:10 volumiothaison ntpd[3341]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 10 04:46:10 volumiothaison ntpd[3341]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 10 04:46:10 volumiothaison ntpd[3341]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 561 days ago Jul 10 04:46:10 volumiothaison ntpd[3341]: Listen and drop on 0 v6wildcard [::]:123 Jul 10 04:46:10 volumiothaison ntpd[3341]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 10 04:46:10 volumiothaison ntpd[3341]: Listen normally on 2 lo 127.0.0.1:123 Jul 10 04:46:10 volumiothaison ntpd[3341]: Listen normally on 3 wlan0 192.168.50.96:123 Jul 10 04:46:10 volumiothaison ntpd[3341]: Listening on routing socket on fd #20 for interface updates Jul 10 04:46:10 volumiothaison ntpd[3341]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 04:46:10 volumiothaison ntpd[3341]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 04:46:10 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:10 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:10 volumiothaison volumio[1246]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 10 04:46:10 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:10 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:10 volumiothaison volumio[1246]: info: Output device has changed, restarting MPD Jul 10 04:46:10 volumiothaison sudo[3346]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3346]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:10 volumiothaison sudo[3346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison sudo[3349]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3349]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison volumio[1246]: info: Output device has changed, restarting Shairport Sync Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:10 volumiothaison sudo[3349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 04:46:10 volumiothaison sudo[3349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison sudo[3346]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison sudo[3349]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison sudo[3352]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3352]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 04:46:10 volumiothaison sudo[3352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison systemd[1]: Stopping Music Player Daemon... Jul 10 04:46:10 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:10 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:10 volumiothaison volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 04:46:10 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:10 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:10 volumiothaison volumio[1246]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison systemd[1]: mpd.service: Succeeded. Jul 10 04:46:10 volumiothaison systemd[1]: Stopped Music Player Daemon. Jul 10 04:46:10 volumiothaison systemd[1]: Starting Music Player Daemon... Jul 10 04:46:10 volumiothaison sudo[3362]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3362]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:10 volumiothaison sudo[3362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison sudo[3362]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison volumio[1246]: No protocol specified Jul 10 04:46:10 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 10 04:46:10 volumiothaison volumio[1246]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 10 04:46:10 volumiothaison sudo[3358]: root : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3358]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3358]: sudo: unable to resolve host volumiothaison: System error Jul 10 04:46:10 volumiothaison sudo[3358]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 04:46:10 volumiothaison sudo[3358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison sudo[3358]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 10 04:46:10 volumiothaison volumio[1246]: info: Disabling external Volume Control Jul 10 04:46:10 volumiothaison sudo[3377]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3377]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:10 volumiothaison sudo[3377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison volumio[1246]: error: [ytcr] Error connecting MPD: Jul 10 04:46:10 volumiothaison volumio[1246]: (Error) write EPIPE Jul 10 04:46:10 volumiothaison volumio[1246]: Error: write EPIPE Jul 10 04:46:10 volumiothaison volumio[1246]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Jul 10 04:46:10 volumiothaison volumio[1246]: at writeGeneric (internal/stream_base_commons.js:147:3) Jul 10 04:46:10 volumiothaison volumio[1246]: at Socket._writeGeneric (net.js:798:11) Jul 10 04:46:10 volumiothaison volumio[1246]: at Socket._write (net.js:810:8) Jul 10 04:46:10 volumiothaison volumio[1246]: at writeOrBuffer (internal/streams/writable.js:358:12) Jul 10 04:46:10 volumiothaison volumio[1246]: at Socket.Writable.write (internal/streams/writable.js:303:10) Jul 10 04:46:10 volumiothaison volumio[1246]: at Timeout._onTimeout (/data/plugins/music_service/ytcr/node_modules/mpd2/lib/index.js:276:18) Jul 10 04:46:10 volumiothaison volumio[1246]: at listOnTimeout (internal/timers.js:557:17) Jul 10 04:46:10 volumiothaison volumio[1246]: at processTimers (internal/timers.js:500:7) Jul 10 04:46:10 volumiothaison volumio[1246]: Retrying in 5 seconds... Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:10 volumiothaison sudo[3377]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison sudo[3382]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3382]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:10 volumiothaison sudo[3382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:10 volumiothaison sudo[3380]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3380]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 04:46:10 volumiothaison sudo[3380]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison sudo[3382]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison sudo[3380]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:46:10 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:10 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:10 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:10 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:10 volumiothaison volumio[1246]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jul 10 04:46:10 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:10 volumiothaison volumio[1246]: amixer: Unable to find simple control '',0 Jul 10 04:46:10 volumiothaison volumio[1246]: info: MPD Permissions set Jul 10 04:46:10 volumiothaison volumio[1246]: info: MPD Permissions set Jul 10 04:46:10 volumiothaison volumio[1246]: info: Shairport-Sync Started Jul 10 04:46:10 volumiothaison volumio[1246]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jul 10 04:46:10 volumiothaison volumio[1246]: info: Sending Spotify command with payload to local API: /player/volume Jul 10 04:46:10 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:10 volumiothaison volumio[1246]: error: Failed to send command to Spotify local API: /player/volume: Error: Internal Server Error Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 10 04:46:10 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:10 volumiothaison volumio[1246]: info: Starting Shairport Sync Jul 10 04:46:10 volumiothaison volumio[1246]: info: Starting Shairport Sync Jul 10 04:46:10 volumiothaison sudo[3401]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3401]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 04:46:10 volumiothaison sudo[3401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison systemd[1]: shairport-sync.service: Succeeded. Jul 10 04:46:10 volumiothaison systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 04:46:10 volumiothaison sudo[3404]: volumio : unable to resolve host volumiothaison Jul 10 04:46:10 volumiothaison sudo[3404]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:10 volumiothaison sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 04:46:10 volumiothaison sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:10 volumiothaison mpd[3369]: Jul 10 04:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:10 volumiothaison volumio[1246]: error: updateQueue error: null Jul 10 04:46:10 volumiothaison systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 04:46:10 volumiothaison systemd[1]: Started Music Player Daemon. Jul 10 04:46:10 volumiothaison sudo[3401]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison sudo[3352]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 04:46:10 volumiothaison systemd[1]: shairport-sync.service: Succeeded. Jul 10 04:46:10 volumiothaison systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 04:46:10 volumiothaison systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 04:46:10 volumiothaison sudo[3404]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:10 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:10 volumiothaison volumio[1246]: info: Shairport-Sync Started Jul 10 04:46:10 volumiothaison volumio[1246]: info: Shairport-Sync Started Jul 10 04:46:10 volumiothaison volumio[1246]: error: updateQueue error: null Jul 10 04:46:11 volumiothaison volumio[1246]: info: Executing endpoint metavolumio Jul 10 04:46:11 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 04:46:11 volumiothaison volumio[1246]: info: Executing endpoint metavolumio Jul 10 04:46:11 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 04:46:11 volumiothaison volumio[1246]: info: Executing endpoint metavolumio Jul 10 04:46:11 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 04:46:11 volumiothaison ntpd[3341]: Soliciting pool server 103.130.217.41 Jul 10 04:46:12 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:12 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:12 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:12 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:12 volumiothaison ntpd[3341]: Soliciting pool server 103.130.217.41 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 10 04:46:12 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:12 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:12 volumiothaison sudo[3414]: volumio : unable to resolve host volumiothaison Jul 10 04:46:12 volumiothaison sudo[3414]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:12 volumiothaison sudo[3414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 04:46:12 volumiothaison sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:12 volumiothaison systemd[1]: Stopping Music Player Daemon... Jul 10 04:46:12 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:12 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:12 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:12 volumiothaison systemd[1]: mpd.service: Succeeded. Jul 10 04:46:12 volumiothaison systemd[1]: Stopped Music Player Daemon. Jul 10 04:46:12 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 10 04:46:12 volumiothaison systemd[1]: Starting Music Player Daemon... Jul 10 04:46:12 volumiothaison volumio[1246]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 04:46:12 volumiothaison volumio[1246]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:12 volumiothaison volumio[1246]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:12 volumiothaison volumio[1246]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:12 volumiothaison volumio[1246]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:12 volumiothaison sudo[3421]: root : unable to resolve host volumiothaison Jul 10 04:46:12 volumiothaison sudo[3421]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:12 volumiothaison sudo[3421]: sudo: unable to resolve host volumiothaison: System error Jul 10 04:46:12 volumiothaison sudo[3421]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 04:46:12 volumiothaison sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:12 volumiothaison sudo[3421]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:12 volumiothaison sudo[3436]: volumio : unable to resolve host volumiothaison Jul 10 04:46:12 volumiothaison sudo[3436]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:12 volumiothaison sudo[3436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 04:46:12 volumiothaison sudo[3436]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:12 volumiothaison systemd[1]: Started Volumio Tidal Connect Service. Jul 10 04:46:12 volumiothaison sudo[3436]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:12 volumiothaison sudo[3439]: volumio : unable to resolve host volumiothaison Jul 10 04:46:12 volumiothaison sudo[3439]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:12 volumiothaison sudo[3439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 04:46:12 volumiothaison sudo[3439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:12 volumiothaison sudo[3439]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:12 volumiothaison volumio[1246]: info: Executing endpoint tc_getconfig Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 10 04:46:12 volumiothaison vtcs[3440]: STARTING TidalConnect services, version: 1.4.0.34 Jul 10 04:46:12 volumiothaison vtcs[3440]: STARTED TidalConnect services. Jul 10 04:46:12 volumiothaison volumio[1246]: info: Executing endpoint tc_connect Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 10 04:46:12 volumiothaison volumio[1246]: info: Connecting to TidalConnect Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::servicePushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 10 04:46:12 volumiothaison volumio[1246]: verbose: CURRENT POSITION 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::syncState stateService stop Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:12 volumiothaison volumio[1246]: info: No code Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::servicePushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 10 04:46:12 volumiothaison volumio[1246]: verbose: CURRENT POSITION 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::syncState stateService stop Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:12 volumiothaison volumio[1246]: info: No code Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:12 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:12 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:12 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:12 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:12 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:12 volumiothaison mpd[3435]: Jul 10 04:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 04:46:12 volumiothaison systemd[1]: Started Music Player Daemon. Jul 10 04:46:12 volumiothaison sudo[3414]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:12 volumiothaison volumio[1246]: error: updateQueue error: null Jul 10 04:46:13 volumiothaison ntpd[3341]: Soliciting pool server 103.130.217.41 Jul 10 04:46:13 volumiothaison volumio[1246]: info: VolumeController::SetAlsaVolume100 Jul 10 04:46:13 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:46:13 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:13 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:13 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 3 Jul 10 04:46:13 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 04:46:13 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:13 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:13 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:13 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:13 volumiothaison volumio[1246]: SPOTIFY: SPOTIFY VOLUME 100 Jul 10 04:46:13 volumiothaison volumio[1246]: SPOTIFY: VOLUMIO VOLUME 100 Jul 10 04:46:13 volumiothaison volumio[1246]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 10 04:46:13 volumiothaison volumio[1246]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jul 10 04:46:13 volumiothaison volumio[1246]: xcb_connection_has_error() returned true Jul 10 04:46:13 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:13 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:13 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:13 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:13 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:13 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:13 volumiothaison volumio[1246]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:13 volumiothaison volumio[1246]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:13 volumiothaison sudo[3473]: volumio : unable to resolve host volumiothaison Jul 10 04:46:13 volumiothaison sudo[3473]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:13 volumiothaison sudo[3473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 04:46:13 volumiothaison sudo[3473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:13 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:13 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:13 volumiothaison sudo[3473]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:13 volumiothaison volumio[1246]: error: Unable to stop TidalConnect service: Jul 10 04:46:13 volumiothaison volumio[1246]: info: TidalConnect service stoped! Jul 10 04:46:13 volumiothaison volumio[1246]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:13 volumiothaison volumio[1246]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:13 volumiothaison volumio[1246]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:13 volumiothaison volumio[1246]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 04:46:13 volumiothaison sudo[3493]: volumio : unable to resolve host volumiothaison Jul 10 04:46:13 volumiothaison sudo[3493]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:13 volumiothaison sudo[3493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 04:46:13 volumiothaison sudo[3493]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:13 volumiothaison sudo[3495]: volumio : unable to resolve host volumiothaison Jul 10 04:46:13 volumiothaison sudo[3495]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:46:13 volumiothaison sudo[3493]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:13 volumiothaison sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 04:46:13 volumiothaison sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 04:46:13 volumiothaison sudo[3495]: pam_unix(sudo:session): session closed for user root Jul 10 04:46:14 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 04:46:14 volumiothaison ntpd[3341]: Soliciting pool server 103.130.217.41 Jul 10 04:46:15 volumiothaison volumio[1246]: error: Unable to start TidalConnect service: Jul 10 04:46:15 volumiothaison volumio[1246]: info: TidalConnect service started! Jul 10 04:46:15 volumiothaison volumio[1246]: error: Unable to start TidalConnect service: Jul 10 04:46:15 volumiothaison volumio[1246]: info: TidalConnect service started! Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioNext Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::next Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::stop Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::play index undefined Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 4 Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::startPlaybackTimer Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 4 Jul 10 04:46:16 volumiothaison volumio[1246]: info: [1720611976664] ControllerUPNPBrowser::clearAddPlayTrack Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::updateTrackBlock Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getTrackBlock Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand stop took 1 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 04:46:16 volumiothaison volumio[1246]: info: Jul 10 04:46:16 volumiothaison volumio[1246]: ---------------------------- MPD announces system playlist update Jul 10 04:46:16 volumiothaison volumio[1246]: info: Ignoring MPD Status Update Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand clear took 2 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.52:50002/m/NDLNA/9413.flac" Jul 10 04:46:16 volumiothaison volumio[1246]: error: updateQueue error: null Jul 10 04:46:16 volumiothaison volumio[1246]: info: ------------------------------ 104ms Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.52:50002/m/NDLNA/9413.flac" Jul 10 04:46:16 volumiothaison volumio[1246]: info: Jul 10 04:46:16 volumiothaison volumio[1246]: ---------------------------- MPD announces system playlist update Jul 10 04:46:16 volumiothaison volumio[1246]: info: Ignoring MPD Status Update Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand add "http://192.168.50.52:50002/m/NDLNA/9413.flac" took 1 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand play Jul 10 04:46:16 volumiothaison volumio[1246]: info: ------------------------------ 1ms Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand play took 0 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: info: Jul 10 04:46:16 volumiothaison volumio[1246]: ---------------------------- MPD announces state update: player Jul 10 04:46:16 volumiothaison volumio[1246]: info: ControllerMpd::getState Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Jul 10 04:46:16 volumiothaison volumio[1246]: info: Jul 10 04:46:16 volumiothaison volumio[1246]: ---------------------------- MPD announces state update: player Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand status took 3 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: info: ControllerMpd::getState Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::parseState Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand status took 1 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::parseState Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::parseTrackInfo Jul 10 04:46:16 volumiothaison volumio[1246]: info: ControllerMpd::pushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::servicePushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 4 Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9413.flac","artist":null,"album":null,"uri":"http://192.168.50.52:50002/m/NDLNA/9413.flac","trackType":"flac"} Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: CURRENT POSITION 4 Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::syncState stateService play Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Jul 10 04:46:16 volumiothaison volumio[1246]: info: ------------------------------ 5ms Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::parseTrackInfo Jul 10 04:46:16 volumiothaison volumio[1246]: info: ControllerMpd::pushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::servicePushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 4 Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9413.flac","artist":null,"album":null,"uri":"http://192.168.50.52:50002/m/NDLNA/9413.flac","trackType":"flac"} Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: CURRENT POSITION 4 Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::syncState stateService play Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::syncState currentStatus play Jul 10 04:46:16 volumiothaison volumio[1246]: info: Received an update from plugin. extracting info from payload Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:16 volumiothaison volumio[1246]: info: ------------------------------ 16ms Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: SPOTIFY VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: SPOTIFY VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 10 04:46:16 volumiothaison volumio[1246]: error: Unable to start TidalConnect service: Jul 10 04:46:16 volumiothaison volumio[1246]: info: TidalConnect service started! Jul 10 04:46:16 volumiothaison volumio[1246]: error: Unable to start TidalConnect service: Jul 10 04:46:16 volumiothaison volumio[1246]: info: TidalConnect service started! Jul 10 04:46:16 volumiothaison volumio[1246]: error: Unable to start TidalConnect service: Jul 10 04:46:16 volumiothaison volumio[1246]: info: TidalConnect service started! Jul 10 04:46:16 volumiothaison volumio[1246]: info: Jul 10 04:46:16 volumiothaison volumio[1246]: ---------------------------- MPD announces system playlist update Jul 10 04:46:16 volumiothaison volumio[1246]: info: Ignoring MPD Status Update Jul 10 04:46:16 volumiothaison volumio[1246]: info: Jul 10 04:46:16 volumiothaison volumio[1246]: ---------------------------- MPD announces state update: player Jul 10 04:46:16 volumiothaison volumio[1246]: info: ControllerMpd::getState Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Jul 10 04:46:16 volumiothaison volumio[1246]: info: ------------------------------ 2ms Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand status took 0 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::parseState Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 04:46:16 volumiothaison volumio[1246]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: ControllerMpd::parseTrackInfo Jul 10 04:46:16 volumiothaison volumio[1246]: info: ControllerMpd::pushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::servicePushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getTrack 4 Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1035,"duration":233,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1866 Kbps","isStreaming":false,"title":"Lan Và Điệp 4 (Phương Anh, Phương Ý)","artist":"Phương Anh, Phương Ý","album":"Tuyển tập Single của Phương Ý ","uri":"http://192.168.50.52:50002/m/NDLNA/9413.flac","trackType":"flac"} Jul 10 04:46:16 volumiothaison volumio[1246]: verbose: CURRENT POSITION 4 Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::syncState stateService play Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::syncState currentStatus play Jul 10 04:46:16 volumiothaison volumio[1246]: info: Received an update from plugin. extracting info from payload Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::pushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioPushState Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Jul 10 04:46:16 volumiothaison volumio[1246]: info: MRS: Pushing multiroomSync output Jul 10 04:46:16 volumiothaison volumio[1246]: info: ------------------------------ 13ms Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetQueue Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::getQueue Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getQueue Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetQueue Jul 10 04:46:16 volumiothaison volumio[1246]: info: CoreStateMachine::getQueue Jul 10 04:46:16 volumiothaison volumio[1246]: info: CorePlayQueue::getQueue Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: SPOTIFY VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: SPOTIFY VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: VOLUMIO VOLUME 100 Jul 10 04:46:16 volumiothaison volumio[1246]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 10 04:46:18 volumiothaison volumio[1246]: info: Executing endpoint metavolumio Jul 10 04:46:18 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 04:46:18 volumiothaison volumio[1246]: info: Executing endpoint metavolumio Jul 10 04:46:18 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 04:46:18 volumiothaison volumio[1246]: info: Executing endpoint metavolumio Jul 10 04:46:18 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 04:47:15 volumiothaison ntpd[3341]: Soliciting pool server 103.130.217.41 Jul 10 04:47:16 volumiothaison ntpd[3341]: Soliciting pool server 103.130.217.41 Jul 10 04:47:18 volumiothaison ntpd[3341]: Soliciting pool server 2401:c080:2400:1c19:5400:4ff:fe0b:be05 Jul 10 04:47:19 volumiothaison ntpd[3341]: Soliciting pool server 103.130.217.41 Jul 10 04:47:23 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 04:47:23 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 04:47:23 volumiothaison volumio[1246]: info: Discovery: Getting this device information Jul 10 04:47:23 volumiothaison volumio[1246]: info: CoreCommandRouter::volumioGetState Jul 10 04:47:23 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 04:47:30 volumiothaison volumio[1246]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 04:47:30 volumiothaison volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 04:47:30 volumiothaison volumio[1246]: TypeError: Cannot read property 'length' of undefined Jul 10 04:47:30 volumiothaison volumio[1246]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 10 04:47:30 volumiothaison volumio[1246]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 10 04:47:30 volumiothaison volumio[1246]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 10 04:47:30 volumiothaison volumio[1246]: at Parser.emit (events.js:400:28) Jul 10 04:47:30 volumiothaison volumio[1246]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 10 04:47:30 volumiothaison volumio[1246]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 10 04:47:30 volumiothaison volumio[1246]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 10 04:47:30 volumiothaison volumio[1246]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 10 04:47:30 volumiothaison volumio[1246]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 10 04:47:30 volumiothaison volumio[1246]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 10 04:47:30 volumiothaison volumio[1246]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 10 04:47:30 volumiothaison volumio[1246]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 10 04:47:30 volumiothaison volumio[1246]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 10 04:47:30 volumiothaison volumio[1246]: at IncomingMessage.emit (events.js:412:35) Jul 10 04:47:30 volumiothaison volumio[1246]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 10 04:47:30 volumiothaison volumio[1246]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 10 04:47:30 volumiothaison volumio[1246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 04:47:30 volumiothaison sudo[3580]: volumio : unable to resolve host volumiothaison Jul 10 04:47:30 volumiothaison sudo[3580]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 10 04:47:30 volumiothaison sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 04:46 Jul 10 04:47:30 volumiothaison sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="de36dcdb7799b932c717b0440e58d5fd01f99639" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 28 Jun 2024 01:37:04 PM CEST" VOLUMIO_VERSION="3.733" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="0df13ec5ba714c2620abe9aa7729b622"