-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2024-05-01 13:46:10 CEST. -- May 01 13:45:01 cloudatlas volumio[899]: info: Disabling MyMusic plugin upnp May 01 13:45:01 cloudatlas sudo[2402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 01 13:45:01 cloudatlas sudo[2402]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:01 cloudatlas systemd[1]: Stopping UPnP Renderer front-end to MPD... May 01 13:45:01 cloudatlas volumio[899]: error: Upnp client error: Error: This socket has been ended by the other party May 01 13:45:01 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:02 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:03 cloudatlas volumio[899]: info: Disabling MyMusic plugin airplay_emulation May 01 13:45:03 cloudatlas volumio[899]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync May 01 13:45:03 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 13:45:03 cloudatlas volumio[899]: Cannot find translation for source USB PnP Audio Device May 01 13:45:03 cloudatlas volumio[899]: Cannot find translation for source TIDAL May 01 13:45:03 cloudatlas volumio[899]: info: Disabling plugin airplay_emulation May 01 13:45:03 cloudatlas volumio[899]: info: Done. May 01 13:45:03 cloudatlas sudo[2405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync May 01 13:45:03 cloudatlas sudo[2405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:03 cloudatlas systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 13:45:03 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:03 cloudatlas systemd[1]: shairport-sync.service: Succeeded. May 01 13:45:03 cloudatlas systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 13:45:03 cloudatlas sudo[2405]: pam_unix(sudo:session): session closed for user root May 01 13:45:03 cloudatlas volumio[899]: info: Shairport-Sync Stopped May 01 13:45:05 cloudatlas volumio[899]: info: Disabling MyMusic plugin upnp_browser May 01 13:45:05 cloudatlas volumio[899]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers May 01 13:45:05 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 13:45:05 cloudatlas volumio[899]: Cannot find translation for source USB PnP Audio Device May 01 13:45:05 cloudatlas volumio[899]: Cannot find translation for source TIDAL May 01 13:45:05 cloudatlas volumio[899]: info: Disabling plugin upnp_browser May 01 13:45:05 cloudatlas volumio[899]: info: Done. May 01 13:45:06 cloudatlas volumio[899]: info: Disabling MyMusic plugin bluetooth May 01 13:45:06 cloudatlas volumio[899]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth May 01 13:45:06 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:06 cloudatlas sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service May 01 13:45:06 cloudatlas sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:06 cloudatlas systemd[1]: Stopping Volumio Bluetooth Module... May 01 13:45:06 cloudatlas volumiobt[2411]: Killing pulse May 01 13:45:06 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:06 cloudatlas volumiobt[2411]: Killing BT Pair agent May 01 13:45:06 cloudatlas bluetoothd[738]: Endpoint unregistered: sender=:1.55 path=/MediaEndpoint/A2DPSource May 01 13:45:06 cloudatlas bluetoothd[738]: Endpoint unregistered: sender=:1.55 path=/MediaEndpoint/A2DPSink May 01 13:45:06 cloudatlas systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM May 01 13:45:06 cloudatlas systemd[1]: volumiobt.service: Succeeded. May 01 13:45:06 cloudatlas systemd[1]: Stopped Volumio Bluetooth Module. May 01 13:45:06 cloudatlas sudo[2409]: pam_unix(sudo:session): session closed for user root May 01 13:45:06 cloudatlas volumio[899]: info: Volumio BT Module successfully stopped May 01 13:45:06 cloudatlas volumio[899]: info: Disabling plugin bluetooth May 01 13:45:08 cloudatlas volumio[899]: info: Disabling MyMusic plugin cd_controller May 01 13:45:08 cloudatlas volumio[899]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service cd_controller May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 13:45:08 cloudatlas volumio[899]: Cannot find translation for source USB PnP Audio Device May 01 13:45:08 cloudatlas volumio[899]: Cannot find translation for source TIDAL May 01 13:45:08 cloudatlas volumio[899]: info: Stopping UDEV Watcher for CD May 01 13:45:08 cloudatlas volumio[899]: info: Disabling plugin cd_controller May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 13:45:08 cloudatlas volumio[899]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 01 13:45:08 cloudatlas volumio[899]: info: Received Get System Version May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 13:45:08 cloudatlas volumio[899]: info: Received Get System Info May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 13:45:08 cloudatlas volumio[899]: info: Discovery: Getting this device information May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::volumioGetState May 01 13:45:08 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:08 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 13:45:10 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:10 cloudatlas volumio[899]: info: Disabling MyMusic plugin upnp May 01 13:45:10 cloudatlas sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 01 13:45:10 cloudatlas sudo[2416]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:10 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:14 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:18 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:20 cloudatlas volumio[899]: info: CALLMETHOD: music_service tidal saveConfig [object Object] May 01 13:45:20 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig May 01 13:45:20 cloudatlas volumio[899]: info: Saving configuration May 01 13:45:20 cloudatlas volumio[899]: info: Done May 01 13:45:22 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:26 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:30 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:34 cloudatlas volumio[899]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] May 01 13:45:34 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility May 01 13:45:34 cloudatlas volumio[899]: info: CoreCommandRouter::volumioUpdateToBrowseSources May 01 13:45:34 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 13:45:34 cloudatlas volumio[899]: Cannot find translation for source USB PnP Audio Device May 01 13:45:34 cloudatlas volumio[899]: Cannot find translation for source TIDAL May 01 13:45:34 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:38 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 13:45:40 cloudatlas volumio[899]: info: CoreCommandRouter::volumioGetState May 01 13:45:40 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 13:45:43 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 13:45:43 cloudatlas volumio[899]: info: Getting Alsa Cards List without I2S DAC May 01 13:45:43 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:43 cloudatlas volumio[899]: Invalid card number. May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 01 13:45:43 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 13:45:49 cloudatlas volumio[899]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::volumioGetState May 01 13:45:49 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:49 cloudatlas volumio[899]: info: Restoring Previous Volume level: 100 false true May 01 13:45:49 cloudatlas volumio[899]: info: VolumeController::SetAlsaVolume100 May 01 13:45:49 cloudatlas volumio[899]: info: Enable softmixer device for audio device number 2 May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::volumioStop May 01 13:45:49 cloudatlas volumio[899]: info: CoreStateMachine::stop May 01 13:45:49 cloudatlas volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 13:45:49 cloudatlas volumio[899]: info: Enable softmixer device for audio device undefined May 01 13:45:49 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 13:45:49 cloudatlas volumio[899]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Digi Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 13:45:49 cloudatlas volumio[899]: info: Disabling external Volume Control May 01 13:45:49 cloudatlas volumio[899]: info: Output device has changed, restarting MPD May 01 13:45:49 cloudatlas sudo[2470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 13:45:49 cloudatlas sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:49 cloudatlas volumio[899]: info: Output device has changed, restarting Shairport Sync May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:49 cloudatlas sudo[2470]: pam_unix(sudo:session): session closed for user root May 01 13:45:49 cloudatlas sudo[2473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 13:45:49 cloudatlas sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:49 cloudatlas systemd[1]: Stopping Music Player Daemon... May 01 13:45:49 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:49 cloudatlas volumio[899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 13:45:49 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:49 cloudatlas volumio[899]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 01 13:45:49 cloudatlas systemd[1]: mpd.service: Succeeded. May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas systemd[1]: Stopped Music Player Daemon. May 01 13:45:49 cloudatlas vtcs[2304]: [2024-05-01 13:45:49.808] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 01 13:45:49 cloudatlas vtcs[2304]: [2024-05-01 13:45:49.808] [tisoc] [error] [SpkconServer.cpp:382] recv error. client fd=8 errorno=104 error=Connection reset by peer May 01 13:45:49 cloudatlas vtcs[2304]: [2024-05-01 13:45:49.808] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected May 01 13:45:49 cloudatlas sudo[2482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:49 cloudatlas volumio[899]: info: Volume configurations have been set May 01 13:45:49 cloudatlas sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:49 cloudatlas systemd[1]: Starting Music Player Daemon... May 01 13:45:49 cloudatlas sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:49 cloudatlas sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:49 cloudatlas systemd[1]: Stopping Volumio Tidal Connect Service... May 01 13:45:49 cloudatlas systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 01 13:45:49 cloudatlas systemd[1]: vtcs.service: Succeeded. May 01 13:45:49 cloudatlas systemd[1]: Stopped Volumio Tidal Connect Service. May 01 13:45:49 cloudatlas sudo[2482]: pam_unix(sudo:session): session closed for user root May 01 13:45:49 cloudatlas sudo[2486]: pam_unix(sudo:session): session closed for user root May 01 13:45:49 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 13:45:49 cloudatlas volumio[899]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Digi Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 13:45:49 cloudatlas volumio[899]: info: Disabling external Volume Control May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 13:45:49 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:49 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:49 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:49 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:49 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:49 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:49 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:49 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:49 cloudatlas sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:49 cloudatlas sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:49 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:49 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:49 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:49 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:49 cloudatlas sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:49 cloudatlas sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:49 cloudatlas sudo[2499]: pam_unix(sudo:session): session closed for user root May 01 13:45:49 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:49 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:49 cloudatlas sudo[2502]: pam_unix(sudo:session): session closed for user root May 01 13:45:49 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:49 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:49 cloudatlas volumio[899]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 01 13:45:49 cloudatlas volumio[899]: info: MPD Permissions set May 01 13:45:49 cloudatlas volumio[899]: info: Software Volume ALSA configuration written May 01 13:45:49 cloudatlas volumio[899]: info: Preparing to generate the ALSA configuration file May 01 13:45:49 cloudatlas volumio[899]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 01 13:45:49 cloudatlas volumio[899]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 01 13:45:49 cloudatlas volumio[899]: info: Reading ALSA contributions from plugins. May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 13:45:49 cloudatlas volumio[899]: info: Getting Alsa Cards List without I2S DAC May 01 13:45:49 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 01 13:45:49 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: Invalid card number. May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 13:45:50 cloudatlas volumio[899]: info: Starting Shairport Sync May 01 13:45:50 cloudatlas volumio[899]: info: Asound.conf file written May 01 13:45:50 cloudatlas sudo[2519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 13:45:50 cloudatlas sudo[2519]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas mpd[2478]: May 01 13:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 13:45:50 cloudatlas systemd[1]: Started Music Player Daemon. May 01 13:45:50 cloudatlas sudo[2473]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 01 13:45:50 cloudatlas sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2524]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi0 May 01 13:45:50 cloudatlas volumio[899]: Found hardware: "vc4-hdmi" "" "" "" "" May 01 13:45:50 cloudatlas volumio[899]: Hardware is initialized using a generic method May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi0 May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi1 May 01 13:45:50 cloudatlas volumio[899]: Found hardware: "vc4-hdmi" "" "" "" "" May 01 13:45:50 cloudatlas volumio[899]: Hardware is initialized using a generic method May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi1 May 01 13:45:50 cloudatlas volumio[899]: No state is present for card Device May 01 13:45:50 cloudatlas volumio[899]: Found hardware: "USB-Audio" "USB Mixer" "USB0c76:1170" "" "" May 01 13:45:50 cloudatlas volumio[899]: Hardware is initialized using a generic method May 01 13:45:50 cloudatlas volumio[899]: No state is present for card Device May 01 13:45:50 cloudatlas volumio[899]: info: Output device has changed, restarting MPD May 01 13:45:50 cloudatlas sudo[2519]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 13:45:50 cloudatlas volumio[899]: info: Output device has changed, restarting Shairport Sync May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:50 cloudatlas sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2530]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas sudo[2534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 13:45:50 cloudatlas sudo[2534]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas systemd[1]: Stopping Music Player Daemon... May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas systemd[1]: mpd.service: Succeeded. May 01 13:45:50 cloudatlas systemd[1]: Stopped Music Player Daemon. May 01 13:45:50 cloudatlas systemd[1]: Starting Music Player Daemon... May 01 13:45:50 cloudatlas sudo[2544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:50 cloudatlas sudo[2544]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2544]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 01 13:45:50 cloudatlas volumio[899]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 01 13:45:50 cloudatlas volumio[899]: aplay: main:828: audio open error: No such device May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi0 May 01 13:45:50 cloudatlas volumio[899]: Found hardware: "vc4-hdmi" "" "" "" "" May 01 13:45:50 cloudatlas volumio[899]: Hardware is initialized using a generic method May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi0 May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi1 May 01 13:45:50 cloudatlas volumio[899]: Found hardware: "vc4-hdmi" "" "" "" "" May 01 13:45:50 cloudatlas volumio[899]: Hardware is initialized using a generic method May 01 13:45:50 cloudatlas volumio[899]: No state is present for card vc4hdmi1 May 01 13:45:50 cloudatlas volumio[899]: No state is present for card Device May 01 13:45:50 cloudatlas volumio[899]: Found hardware: "USB-Audio" "USB Mixer" "USB0c76:1170" "" "" May 01 13:45:50 cloudatlas volumio[899]: Hardware is initialized using a generic method May 01 13:45:50 cloudatlas volumio[899]: No state is present for card Device May 01 13:45:50 cloudatlas volumio[899]: info: Output device has changed, restarting MPD May 01 13:45:50 cloudatlas sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:50 cloudatlas sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas volumio[899]: info: Output device has changed, restarting Shairport Sync May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:50 cloudatlas sudo[2552]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 13:45:50 cloudatlas sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2555]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 13:45:50 cloudatlas sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 01 13:45:50 cloudatlas systemd[1]: mpd.service: Succeeded. May 01 13:45:50 cloudatlas systemd[1]: Stopped Music Player Daemon. May 01 13:45:50 cloudatlas sudo[2568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:50 cloudatlas sudo[2568]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas systemd[1]: Starting Music Player Daemon... May 01 13:45:50 cloudatlas sudo[2568]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 13:45:50 cloudatlas volumio[899]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 13:45:50 cloudatlas volumio[899]: info: Disabling external Volume Control May 01 13:45:50 cloudatlas sudo[2581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:50 cloudatlas sudo[2581]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:50 cloudatlas sudo[2583]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:50 cloudatlas sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2581]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:50 cloudatlas sudo[2583]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:50 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:50 cloudatlas sudo[2587]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:50 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:50 cloudatlas volumio[899]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 01 13:45:50 cloudatlas volumio[899]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 01 13:45:50 cloudatlas volumio[899]: info: MPD Permissions set May 01 13:45:50 cloudatlas volumio[899]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 01 13:45:50 cloudatlas volumio[899]: info: MPD Permissions set May 01 13:45:50 cloudatlas volumio[899]: info: Shairport-Sync Started May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:50 cloudatlas volumio[899]: info: Starting Shairport Sync May 01 13:45:50 cloudatlas volumio[899]: info: Starting Shairport Sync May 01 13:45:50 cloudatlas sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 13:45:50 cloudatlas sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 13:45:50 cloudatlas sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:50 cloudatlas systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:50 cloudatlas systemd[1]: shairport-sync.service: Succeeded. May 01 13:45:50 cloudatlas systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:50 cloudatlas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 13:45:50 cloudatlas systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 13:45:50 cloudatlas systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 01 13:45:50 cloudatlas systemd[1]: shairport-sync.service: Succeeded. May 01 13:45:50 cloudatlas systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:50 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:50 cloudatlas sudo[2601]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 13:45:50 cloudatlas volumio[899]: info: Shairport-Sync Started May 01 13:45:50 cloudatlas mpd[2564]: May 01 13:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 13:45:50 cloudatlas systemd[1]: Started Music Player Daemon. May 01 13:45:50 cloudatlas volumio[899]: (node:899) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 01 13:45:50 cloudatlas volumio[899]: (Use `node --trace-warnings ...` to show where the warning was created) May 01 13:45:50 cloudatlas volumio[899]: (node:899) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 01 13:45:50 cloudatlas volumio[899]: (node:899) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 01 13:45:50 cloudatlas volumio[899]: (node:899) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 01 13:45:50 cloudatlas volumio[899]: (node:899) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 01 13:45:50 cloudatlas sudo[2604]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: info: Shairport-Sync Started May 01 13:45:50 cloudatlas sudo[2557]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: error: updateQueue error: null May 01 13:45:50 cloudatlas sudo[2534]: pam_unix(sudo:session): session closed for user root May 01 13:45:50 cloudatlas volumio[899]: error: MPD error: The expression evaluated to a falsy value: May 01 13:45:50 cloudatlas volumio[899]: assert.ok(self.idling) May 01 13:45:50 cloudatlas volumio[899]: error: The expression evaluated to a falsy value: May 01 13:45:50 cloudatlas volumio[899]: assert.ok(self.idling) May 01 13:45:50 cloudatlas volumio[899]: error: updateQueue error: null May 01 13:45:51 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 01 13:45:51 cloudatlas sudo[2613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 13:45:51 cloudatlas sudo[2613]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:51 cloudatlas systemd[1]: Stopping Music Player Daemon... May 01 13:45:51 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:51 cloudatlas systemd[1]: mpd.service: Succeeded. May 01 13:45:51 cloudatlas systemd[1]: Stopped Music Player Daemon. May 01 13:45:51 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 01 13:45:51 cloudatlas systemd[1]: Starting Music Player Daemon... May 01 13:45:51 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:52 cloudatlas mpd[2615]: May 01 13:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 13:45:52 cloudatlas systemd[1]: Started Music Player Daemon. May 01 13:45:52 cloudatlas sudo[2613]: pam_unix(sudo:session): session closed for user root May 01 13:45:52 cloudatlas volumio[899]: error: updateQueue error: null May 01 13:45:52 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:52 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:52 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:52 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:52 cloudatlas volumio[899]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:52 cloudatlas volumio[899]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:52 cloudatlas sudo[2632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 01 13:45:52 cloudatlas sudo[2632]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:52 cloudatlas volumio[899]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:52 cloudatlas volumio[899]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:52 cloudatlas sudo[2640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 01 13:45:52 cloudatlas sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:53 cloudatlas systemd[1]: Started Volumio Tidal Connect Service. May 01 13:45:53 cloudatlas sudo[2632]: pam_unix(sudo:session): session closed for user root May 01 13:45:53 cloudatlas sudo[2640]: pam_unix(sudo:session): session closed for user root May 01 13:45:53 cloudatlas volumio[899]: info: Executing endpoint tc_getconfig May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 01 13:45:53 cloudatlas vtcs[2639]: STARTING TidalConnect services, version: 1.3.0.19 May 01 13:45:53 cloudatlas vtcs[2639]: STARTED TidalConnect services. May 01 13:45:53 cloudatlas volumio[899]: info: Executing endpoint tc_connect May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 01 13:45:53 cloudatlas volumio[899]: info: Connecting to TidalConnect May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::servicePushState May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: 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} May 01 13:45:53 cloudatlas volumio[899]: verbose: CURRENT POSITION 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::syncState stateService stop May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::syncState currentStatus stop May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: info: No code May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::servicePushState May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: 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} May 01 13:45:53 cloudatlas volumio[899]: verbose: CURRENT POSITION 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::syncState stateService stop May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::syncState currentStatus stop May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: info: No code May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:53 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:53 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:53 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:53 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:53 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:53 cloudatlas volumio[899]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:53 cloudatlas volumio[899]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:53 cloudatlas volumio[899]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:53 cloudatlas volumio[899]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:53 cloudatlas volumio[899]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:53 cloudatlas volumio[899]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:53 cloudatlas sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 01 13:45:53 cloudatlas sudo[2685]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:53 cloudatlas sudo[2688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 01 13:45:53 cloudatlas sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:53 cloudatlas sudo[2685]: pam_unix(sudo:session): session closed for user root May 01 13:45:53 cloudatlas sudo[2690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 01 13:45:53 cloudatlas sudo[2690]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:53 cloudatlas sudo[2688]: pam_unix(sudo:session): session closed for user root May 01 13:45:53 cloudatlas sudo[2690]: pam_unix(sudo:session): session closed for user root May 01 13:45:53 cloudatlas volumio[899]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioGetState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: Restoring Previous Volume level: false true May 01 13:45:53 cloudatlas volumio[899]: info: VolumeController::SetAlsaVolume100 May 01 13:45:53 cloudatlas volumio[899]: info: Volume configurations have been set May 01 13:45:53 cloudatlas vtcs[2639]: [2024-05-01 13:45:53.535] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected May 01 13:45:53 cloudatlas vtcs[2639]: [2024-05-01 13:45:53.535] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 01 13:45:53 cloudatlas sudo[2698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:53 cloudatlas sudo[2698]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:53 cloudatlas systemd[1]: Stopping Volumio Tidal Connect Service... May 01 13:45:53 cloudatlas systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 01 13:45:53 cloudatlas systemd[1]: vtcs.service: Succeeded. May 01 13:45:53 cloudatlas systemd[1]: Stopped Volumio Tidal Connect Service. May 01 13:45:53 cloudatlas sudo[2698]: pam_unix(sudo:session): session closed for user root May 01 13:45:53 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 01 13:45:53 cloudatlas volumio[899]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 01 13:45:53 cloudatlas volumio[899]: info: Disabling external Volume Control May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:45:53 cloudatlas sudo[2711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 01 13:45:53 cloudatlas sudo[2711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:53 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:53 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:53 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:53 cloudatlas volumio[899]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 01 13:45:53 cloudatlas sudo[2711]: pam_unix(sudo:session): session closed for user root May 01 13:45:53 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 13:45:53 cloudatlas volumio[899]: info: Getting Alsa Cards List without I2S DAC May 01 13:45:53 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: xcb_connection_has_error() returned true May 01 13:45:53 cloudatlas volumio[899]: Invalid card number. May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 01 13:45:53 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 13:45:54 cloudatlas volumio[899]: info: VolumeController::SetAlsaVolume100 May 01 13:45:54 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:54 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:54 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 13:45:54 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:54 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:54 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:54 cloudatlas volumio[899]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 01 13:45:54 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 13:45:54 cloudatlas volumio[899]: info: VolumeController:: Setting startup Volume 30 May 01 13:45:54 cloudatlas volumio[899]: info: VolumeController::SetAlsaVolume30 May 01 13:45:54 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:54 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:54 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 13:45:54 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:54 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:54 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:54 cloudatlas volumio[899]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 01 13:45:56 cloudatlas volumio[899]: info: TidalConnect service started! May 01 13:45:56 cloudatlas volumio[899]: info: TidalConnect service started! May 01 13:45:56 cloudatlas volumio[899]: info: TidalConnect service started! May 01 13:45:56 cloudatlas volumio[899]: info: TidalConnect service started! May 01 13:45:56 cloudatlas volumio[899]: info: TidalConnect service started! May 01 13:45:56 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:56 cloudatlas volumio[899]: info: TidalConnect service stoped! May 01 13:45:56 cloudatlas volumio[899]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:56 cloudatlas volumio[899]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 01 13:45:56 cloudatlas sudo[2733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 01 13:45:56 cloudatlas sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:56 cloudatlas systemd[1]: Started Volumio Tidal Connect Service. May 01 13:45:56 cloudatlas sudo[2733]: pam_unix(sudo:session): session closed for user root May 01 13:45:56 cloudatlas volumio[899]: info: Executing endpoint tc_getconfig May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 01 13:45:56 cloudatlas vtcs[2735]: STARTING TidalConnect services, version: 1.3.0.19 May 01 13:45:56 cloudatlas vtcs[2735]: STARTED TidalConnect services. May 01 13:45:56 cloudatlas volumio[899]: info: Executing endpoint tc_connect May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 01 13:45:56 cloudatlas volumio[899]: info: Connecting to TidalConnect May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::servicePushState May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: 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} May 01 13:45:56 cloudatlas volumio[899]: verbose: CURRENT POSITION 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::syncState stateService stop May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::syncState currentStatus stop May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:56 cloudatlas volumio[899]: info: No code May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::servicePushState May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: 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} May 01 13:45:56 cloudatlas volumio[899]: verbose: CURRENT POSITION 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::syncState stateService stop May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::syncState currentStatus stop May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:56 cloudatlas volumio[899]: info: No code May 01 13:45:56 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:56 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:56 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:56 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:58 cloudatlas volumio[899]: info: VolumeController::SetAlsaVolumeNaN May 01 13:45:58 cloudatlas volumio[899]: info: CoreStateMachine::pushState May 01 13:45:58 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:58 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 13:45:58 cloudatlas volumio[899]: info: CoreCommandRouter::volumioPushState May 01 13:45:58 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output update for this device May 01 13:45:58 cloudatlas volumio[899]: info: MRS: Pushing multiroomSync output May 01 13:45:58 cloudatlas volumio[899]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 01 13:45:58 cloudatlas volumio[899]: info: CoreCommandRouter::volumioGetState May 01 13:45:58 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:45:59 cloudatlas volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 13:45:59 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 13:45:59 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 01 13:45:59 cloudatlas sudo[2758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 01 13:45:59 cloudatlas sudo[2758]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:59 cloudatlas sudo[2764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 13:45:59 cloudatlas sudo[2758]: pam_unix(sudo:session): session closed for user root May 01 13:45:59 cloudatlas sudo[2764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:59 cloudatlas sudo[2764]: pam_unix(sudo:session): session closed for user root May 01 13:45:59 cloudatlas sudo[2772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 13:45:59 cloudatlas sudo[2772]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:59 cloudatlas sudo[2772]: pam_unix(sudo:session): session closed for user root May 01 13:45:59 cloudatlas sudo[2777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 13:45:59 cloudatlas sudo[2777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:59 cloudatlas sudo[2777]: pam_unix(sudo:session): session closed for user root May 01 13:45:59 cloudatlas sudo[2784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 13:45:59 cloudatlas sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:59 cloudatlas volumio[899]: info: TidalConnect service started! May 01 13:45:59 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 01 13:45:59 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 01 13:45:59 cloudatlas sudo[2784]: pam_unix(sudo:session): session closed for user root May 01 13:45:59 cloudatlas sudo[2786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 13:45:59 cloudatlas sudo[2786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:45:59 cloudatlas sudo[2786]: pam_unix(sudo:session): session closed for user root May 01 13:45:59 cloudatlas sudo[2790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 13:45:59 cloudatlas sudo[2790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:46:03 cloudatlas sudo[2790]: pam_unix(sudo:session): session closed for user root May 01 13:46:04 cloudatlas volumio[899]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] May 01 13:46:04 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet May 01 13:46:04 cloudatlas sudo[2793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces May 01 13:46:04 cloudatlas sudo[2793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:46:04 cloudatlas sudo[2793]: pam_unix(sudo:session): session closed for user root May 01 13:46:04 cloudatlas sudo[2795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf May 01 13:46:04 cloudatlas sudo[2795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:46:04 cloudatlas sudo[2795]: pam_unix(sudo:session): session closed for user root May 01 13:46:04 cloudatlas volumio[899]: info: Permissions for /etc/network/interfaces set May 01 13:46:04 cloudatlas volumio[899]: info: Restarting Wireless Network May 01 13:46:04 cloudatlas volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 01 13:46:04 cloudatlas volumio[899]: info: Discovery: Restarting Advertising due to device name change May 01 13:46:04 cloudatlas sudo[2798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 01 13:46:04 cloudatlas sudo[2798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:46:04 cloudatlas systemd[1]: Starting Wireless Services... May 01 13:46:04 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:46:04 cloudatlas wireless.js[2800]: Cleaning previous... May 01 13:46:05 cloudatlas systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 01 13:46:05 cloudatlas volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 13:46:05 cloudatlas systemd[1]: hostapd.service: Succeeded. May 01 13:46:05 cloudatlas systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 01 13:46:05 cloudatlas sudo[2815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 01 13:46:05 cloudatlas sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:46:05 cloudatlas avahi-daemon[628]: Withdrawing address record for 192.168.211.1 on wlan0. May 01 13:46:05 cloudatlas avahi-daemon[628]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 01 13:46:05 cloudatlas avahi-daemon[628]: Interface wlan0.IPv4 no longer relevant for mDNS. May 01 13:46:05 cloudatlas sudo[2815]: pam_unix(sudo:session): session closed for user root May 01 13:46:05 cloudatlas volumio[899]: info: Discovery: A device disappeared from network May 01 13:46:05 cloudatlas sudo[2817]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 01 13:46:05 cloudatlas sudo[2817]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 13:46:05 cloudatlas volumio[899]: info: Discovery: A device disappeared from network May 01 13:46:06 cloudatlas sudo[2817]: pam_unix(sudo:session): session closed for user root May 01 13:46:06 cloudatlas sudo[2402]: pam_unix(sudo:session): session closed for user root May 01 13:46:06 cloudatlas sudo[2416]: pam_unix(sudo:session): session closed for user root May 01 13:46:06 cloudatlas volumio[899]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /bin/systemctl stop upmpdcli.service May 01 13:46:06 cloudatlas volumio[899]: Terminated May 01 13:46:06 cloudatlas volumio[899]: info: Error: May 01 13:46:06 cloudatlas volumio[899]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /bin/systemctl stop upmpdcli.service May 01 13:46:06 cloudatlas volumio[899]: Terminated May 01 13:46:06 cloudatlas volumio[899]: info: Error: May 01 13:46:06 cloudatlas wireless.js[2800]: Stopped aP May 01 13:46:06 cloudatlas wireless.js[2800]: Wireless Networking DISABLED, not starting wireless flow May 01 13:46:06 cloudatlas systemd[1]: wireless.service: Succeeded. May 01 13:46:06 cloudatlas systemd[1]: Started Wireless Services. May 01 13:46:06 cloudatlas sudo[2798]: pam_unix(sudo:session): session closed for user root May 01 13:46:06 cloudatlas ntpd[819]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 01 13:46:07 cloudatlas ntpd[819]: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=365 secs May 01 13:46:09 cloudatlas volumio[899]: info: Discovery: Started advertising with name: cloudatlas May 01 13:46:10 cloudatlas volumio[899]: info: Discovery: adding undefined May 01 13:46:10 cloudatlas volumio[899]: info: Discovery: Found device cloudatlas May 01 13:46:10 cloudatlas volumio[899]: info: CoreCommandRouter::volumioGetState May 01 13:46:10 cloudatlas volumio[899]: info: CorePlayQueue::getTrack 0 May 01 13:46:10 cloudatlas volumio[899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 13:46:10 cloudatlas volumio[899]: Error: The value NaN is not a number May 01 13:46:10 cloudatlas volumio[899]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 01 13:46:10 cloudatlas volumio[899]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 01 13:46:10 cloudatlas volumio[899]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 01 13:46:10 cloudatlas volumio[899]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 01 13:46:10 cloudatlas volumio[899]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 01 13:46:10 cloudatlas volumio[899]: at Browser.emit (events.js:315:20) May 01 13:46:10 cloudatlas volumio[899]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 01 13:46:10 cloudatlas volumio[899]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 01 13:46:10 cloudatlas volumio[899]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 01 13:46:10 cloudatlas volumio[899]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 01 13:46:10 cloudatlas volumio[899]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 01 13:46:10 cloudatlas volumio[899]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 01 13:46:10 cloudatlas volumio[899]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 01 13:46:10 cloudatlas volumio[899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 13:46:10 cloudatlas sudo[2836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 13:45 May 01 13:46:10 cloudatlas sudo[2836]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"