-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Fri 2025-09-05 00:37:53 +04. -- Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:36:24 volumio volumio[807]: info: Retrieving Cloud Streaming UI Sep 05 00:36:24 volumio volumio[807]: info: Getting Tidal Cloud Configuration Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:36:24 volumio volumio[807]: info: Getting Qobuz Cloud Configuration Sep 05 00:36:24 volumio volumio[807]: info: Asking plugin for UI Config Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:36:24 volumio volumio[807]: info: Getting Spotify Cloud Configuration Sep 05 00:36:24 volumio volumio[807]: info: Asking plugin for UI Config Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:36:24 volumio volumio[807]: info: Saving Spotify Acccount Sep 05 00:36:24 volumio volumio[807]: info: Got Tidal Cloud Configuration Sep 05 00:36:24 volumio volumio[807]: info: Got it Sep 05 00:36:24 volumio volumio[807]: info: Got it Sep 05 00:36:24 volumio volumio[807]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:36:24 volumio volumio[807]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 00:36:24 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 05 00:36:29 volumio volumio[807]: info: CoreCommandRouter::volumioGetState Sep 05 00:36:29 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 05 00:36:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 00:36:34 volumio volumio[807]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 05 00:36:34 volumio volumio[807]: info: Preparing to save Alsa Options, stopping services first Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::volumioGetState Sep 05 00:36:34 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::volumioPause Sep 05 00:36:34 volumio volumio[807]: info: CoreStateMachine::pause Sep 05 00:36:34 volumio volumio[807]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Gustard USB Audio 2.0"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:36:34 volumio volumio[807]: info: Setting mixer Gustard USB Audio 2.0 for card Gustard USB Audio 2.0 Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 05 00:36:34 volumio volumio[807]: info: Updating Volume Controller Parameters: Device: 5 Name: Gustard USB Audio 2.0 Mixer: Gustard USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 05 00:36:34 volumio volumio[807]: info: Disabling external Volume Control Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:36:34 volumio volumio[807]: info: Preparing to generate the ALSA configuration file Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 00:36:34 volumio volumio[807]: info: Asound.conf file unchanged, so no further update is needed Sep 05 00:36:34 volumio volumio[807]: info: Output device has changed, restarting MPD Sep 05 00:36:34 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 00:36:34 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:36:34 volumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Sep 05 00:36:34 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:36:34 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:36:34 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Sep 05 00:36:34 volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 00:36:34 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:36:34 volumio volumio[807]: info: MPD Permissions set Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:36:34 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 00:36:34 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:36:34 volumio systemd[1]: mpd.service: Succeeded. Sep 05 00:36:34 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 00:36:34 volumio systemd[1]: Starting Music Player Daemon... Sep 05 00:36:34 volumio sudo[1494]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 00:36:34 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:36:34 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root Sep 05 00:36:35 volumio mpd[1506]: Sep 05 00:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 00:36:35 volumio systemd[1]: Started Music Player Daemon. Sep 05 00:36:35 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root Sep 05 00:36:35 volumio volumio[807]: error: updateQueue error: null Sep 05 00:36:35 volumio volumio[807]: info: CoreCommandRouter::volumioGetState Sep 05 00:36:35 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:36:38 volumio volumio[807]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 05 00:36:38 volumio volumio[807]: info: CoreStateMachine::getcurrentVolume Sep 05 00:36:38 volumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 00:36:38 volumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Sep 05 00:36:38 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:36:38 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:36:38 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:36:38 volumio volumio[807]: info: CoreStateMachine::updateTrackBlock Sep 05 00:36:38 volumio volumio[807]: info: CorePlayQueue::getTrackBlock Sep 05 00:36:38 volumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 00:36:38 volumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Sep 05 00:36:38 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:36:38 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:36:38 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:36:41 volumio volumio[807]: info: Clearing queue after UPNP request Sep 05 00:36:41 volumio volumio[807]: info: CoreStateMachine::ClearQueue Sep 05 00:36:41 volumio volumio[807]: info: CoreStateMachine::stop Sep 05 00:36:41 volumio volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 00:36:41 volumio volumio[807]: info: CorePlayQueue::clearPlayQueue Sep 05 00:36:41 volumio volumio[807]: info: CorePlayQueue::saveQueue Sep 05 00:36:41 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:36:41 volumio volumio[807]: info: CoreCommandRouter::volumioPushQueue Sep 05 00:36:41 volumio volumio[807]: error: Upnp client error: Error: This socket has been ended by the other party Sep 05 00:36:43 volumio volumio[807]: info: Starting UPNP Playback Sep 05 00:36:43 volumio volumio[807]: info: Preparing playback through UPNP Sep 05 00:36:43 volumio volumio[807]: info: CoreCommandRouter::volumioGetState Sep 05 00:36:43 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:36:43 volumio volumio[807]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:43 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:43 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:43 volumio volumio[807]: info: ------------------------------ 8ms Sep 05 00:36:43 volumio volumio[807]: info: ------------------------------ 4ms Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:43 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:43 volumio volumio[807]: info: ------------------------------ 4ms Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:43 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:43 volumio volumio[807]: info: ------------------------------ 16ms Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:43 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:43 volumio volumio[807]: info: ------------------------------ 3ms Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:43 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:43 volumio volumio[807]: info: ------------------------------ 3ms Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces state update: player Sep 05 00:36:43 volumio volumio[807]: info: ControllerMpd::getState Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 00:36:43 volumio volumio[807]: info: Sep 05 00:36:43 volumio volumio[807]: ---------------------------- MPD announces state update: player Sep 05 00:36:43 volumio volumio[807]: info: sendMpdCommand status took 115 milliseconds Sep 05 00:36:43 volumio volumio[807]: info: ControllerMpd::getState Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::parseState Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 00:36:43 volumio volumio[807]: info: sendMpdCommand status took 23 milliseconds Sep 05 00:36:43 volumio volumio[807]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::parseState Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 00:36:43 volumio volumio[807]: info: ControllerMpd::pushState Sep 05 00:36:43 volumio volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 00:36:43 volumio volumio[807]: verbose: In UPNP mode Sep 05 00:36:43 volumio volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In My Life","artist":"Veronica Mortensen","album":"Happiness…Is Not Included","uri":"http://192.168.1.5:49152/audirvana/audio_f_2.wav","trackType":"wav"} Sep 05 00:36:43 volumio volumio[807]: verbose: CURRENT POSITION 0 Sep 05 00:36:43 volumio volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 00:36:43 volumio volumio[807]: info: CoreStateMachine::syncState currentStatus stop Sep 05 00:36:43 volumio volumio[807]: info: ------------------------------ 152ms Sep 05 00:36:43 volumio volumio[807]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 05 00:36:43 volumio volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 00:36:43 volumio volumio[807]: info: ControllerMpd::pushState Sep 05 00:36:43 volumio volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 00:36:43 volumio volumio[807]: verbose: In UPNP mode Sep 05 00:36:43 volumio volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In My Life","artist":"Veronica Mortensen","album":"Happiness…Is Not Included","uri":"http://192.168.1.5:49152/audirvana/audio_f_2.wav","trackType":"wav"} Sep 05 00:36:43 volumio volumio[807]: verbose: CURRENT POSITION 0 Sep 05 00:36:43 volumio volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 00:36:43 volumio volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 00:36:43 volumio volumio[807]: info: Received an update from plugin. extracting info from payload Sep 05 00:36:43 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 00:36:43 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:36:43 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 00:36:43 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:36:44 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:36:44 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:36:44 volumio volumio[807]: info: ------------------------------ 63ms Sep 05 00:36:44 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:36:44 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:36:44 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:36:44 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:36:44 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:36:44 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:36:45 volumio volumio[807]: info: Starting UPNP Playback Sep 05 00:36:45 volumio volumio[807]: info: Preparing playback through UPNP Sep 05 00:36:45 volumio volumio[807]: info: Consume mode Sep 05 00:36:45 volumio volumio[807]: info: CoreCommandRouter::volumioGetState Sep 05 00:36:45 volumio volumio[807]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 00:36:45 volumio volumio[807]: info: Sep 05 00:36:45 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:45 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:45 volumio volumio[807]: info: Sep 05 00:36:45 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:45 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:45 volumio volumio[807]: info: ------------------------------ 3ms Sep 05 00:36:45 volumio volumio[807]: info: ------------------------------ 3ms Sep 05 00:36:45 volumio volumio[807]: info: Sep 05 00:36:45 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:45 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:45 volumio volumio[807]: info: ------------------------------ 3ms Sep 05 00:36:45 volumio volumio[807]: info: Sep 05 00:36:45 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:45 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:45 volumio volumio[807]: info: Sep 05 00:36:45 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:45 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:45 volumio volumio[807]: info: ------------------------------ 4ms Sep 05 00:36:45 volumio volumio[807]: info: ------------------------------ 2ms Sep 05 00:36:45 volumio volumio[807]: info: Sep 05 00:36:45 volumio volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 00:36:45 volumio volumio[807]: info: Ignoring MPD Status Update Sep 05 00:36:45 volumio volumio[807]: info: ------------------------------ 2ms Sep 05 00:36:48 volumio volumio[807]: info: Sep 05 00:36:48 volumio volumio[807]: ---------------------------- MPD announces state update: player Sep 05 00:36:48 volumio volumio[807]: info: ControllerMpd::getState Sep 05 00:36:48 volumio volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 00:36:48 volumio volumio[807]: info: sendMpdCommand status took 2 milliseconds Sep 05 00:36:48 volumio volumio[807]: verbose: ControllerMpd::parseState Sep 05 00:36:48 volumio volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 00:36:48 volumio volumio[807]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 00:36:48 volumio volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 00:36:48 volumio volumio[807]: info: ControllerMpd::pushState Sep 05 00:36:48 volumio volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 00:36:48 volumio volumio[807]: verbose: In UPNP mode Sep 05 00:36:48 volumio volumio[807]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7092,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In My Life","artist":"Veronica Mortensen","album":"Happiness…Is Not Included","uri":"http://192.168.1.5:49152/audirvana/audio_f_2.wav","trackType":"wav"} Sep 05 00:36:48 volumio volumio[807]: verbose: CURRENT POSITION 0 Sep 05 00:36:48 volumio volumio[807]: info: CoreStateMachine::syncState stateService pause Sep 05 00:36:48 volumio volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 00:36:48 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:36:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 00:36:48 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:36:48 volumio volumio[807]: info: CoreStateMachine::stPlaybackTimer Sep 05 00:36:48 volumio volumio[807]: info: ------------------------------ 29ms Sep 05 00:36:48 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:36:48 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:36:48 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:37:01 volumio kernel: usb 1-1: USB disconnect, device number 2 Sep 05 00:37:01 volumio volumio[807]: info: Sep 05 00:37:01 volumio volumio[807]: ---------------------------- USB Audio Device Detached Sep 05 00:37:01 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Sep 05 00:37:01 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:01 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Sep 05 00:37:29 volumio kernel: usb 1-1: new full-speed USB device number 3 using xhci-hcd Sep 05 00:37:30 volumio kernel: usb 1-1: not running at top speed; connect to a high speed hub Sep 05 00:37:30 volumio kernel: usb 1-1: New USB device found, idVendor=20b1, idProduct=3033, bcdDevice= c.11 Sep 05 00:37:30 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Sep 05 00:37:30 volumio kernel: usb 1-1: Product: Gustard USB Audio 2.0 Sep 05 00:37:30 volumio kernel: usb 1-1: Manufacturer: Gustard Sep 05 00:37:30 volumio kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 Sep 05 00:37:30 volumio kernel: hid-generic 0003:20B1:3033.0002: hiddev96,hidraw0: USB HID v1.10 Device [Gustard Gustard USB Audio 2.0] on usb-fe9c0000.xhci-1/input3 Sep 05 00:37:30 volumio volumio[807]: info: Sep 05 00:37:30 volumio volumio[807]: ---------------------------- USB Audio Device Attached Sep 05 00:37:30 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::Close All Modals sent Sep 05 00:37:31 volumio volumio[807]: info: Preparing to save Alsa Options, stopping services first Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::volumioGetState Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::volumioPause Sep 05 00:37:31 volumio volumio[807]: info: CoreStateMachine::pause Sep 05 00:37:31 volumio volumio[807]: info: CoreStateMachine::stPlaybackTimer Sep 05 00:37:31 volumio volumio[807]: info: CoreStateMachine::servicePause Sep 05 00:37:31 volumio volumio[807]: info: CorePlayQueue::getTrack 0 Sep 05 00:37:31 volumio volumio[807]: info: Error: no service or no trackblock to pause Sep 05 00:37:31 volumio volumio[807]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Gustard USB Audio 2.0","alsacard":"G20"},"i2s":false} Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:31 volumio volumio[807]: info: Setting mixer Gustard USB Audio 2.0 for card Gustard USB Audio 2.0 Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 05 00:37:31 volumio volumio[807]: info: Updating Volume Controller Parameters: Device: 5 Name: Gustard USB Audio 2.0 Mixer: Gustard USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 05 00:37:31 volumio volumio[807]: info: Disabling external Volume Control Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:31 volumio volumio[807]: info: Preparing to generate the ALSA configuration file Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 00:37:31 volumio volumio[807]: info: Asound.conf file unchanged, so no further update is needed Sep 05 00:37:31 volumio volumio[807]: info: Output device has changed, restarting MPD Sep 05 00:37:31 volumio sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 00:37:31 volumio sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:37:31 volumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Sep 05 00:37:31 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:37:31 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Sep 05 00:37:31 volumio sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 00:37:31 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:37:31 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:37:31 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:37:31 volumio volumio[807]: info: MPD Permissions set Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:31 volumio systemd[1]: mpd.service: Succeeded. Sep 05 00:37:31 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 00:37:31 volumio systemd[1]: Starting Music Player Daemon... Sep 05 00:37:31 volumio sudo[1744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 00:37:31 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:37:31 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Sep 05 00:37:31 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Sep 05 00:37:32 volumio mpd[1746]: Sep 05 00:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 00:37:32 volumio systemd[1]: Started Music Player Daemon. Sep 05 00:37:32 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Sep 05 00:37:32 volumio volumio[807]: error: updateQueue error: null Sep 05 00:37:32 volumio kernel: usb 1-1: USB disconnect, device number 3 Sep 05 00:37:32 volumio volumio[807]: info: Sep 05 00:37:32 volumio volumio[807]: ---------------------------- USB Audio Device Detached Sep 05 00:37:32 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Sep 05 00:37:32 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:32 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Sep 05 00:37:34 volumio kernel: usb 1-1: new full-speed USB device number 4 using xhci-hcd Sep 05 00:37:34 volumio kernel: usb 1-1: not running at top speed; connect to a high speed hub Sep 05 00:37:35 volumio kernel: usb 1-1: New USB device found, idVendor=20b1, idProduct=3033, bcdDevice= c.11 Sep 05 00:37:35 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Sep 05 00:37:35 volumio kernel: usb 1-1: Product: Gustard USB Audio 2.0 Sep 05 00:37:35 volumio kernel: usb 1-1: Manufacturer: Gustard Sep 05 00:37:35 volumio kernel: usb 1-1: 1:3 : unsupported format bits 0x100000000 Sep 05 00:37:35 volumio kernel: hid-generic 0003:20B1:3033.0003: hiddev96,hidraw0: USB HID v1.10 Device [Gustard Gustard USB Audio 2.0] on usb-fe9c0000.xhci-1/input3 Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::getcurrentVolume Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 00:37:35 volumio volumio[807]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 05 00:37:35 volumio volumio[807]: info: VolumeController:: Volume=undefined Mute =false Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::updateTrackBlock Sep 05 00:37:35 volumio volumio[807]: info: CorePlayQueue::getTrackBlock Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:37:35 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:37:35 volumio volumio[807]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Sep 05 00:37:35 volumio volumio[807]: info: VolumeController:: Volume=undefined Mute =false Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:37:35 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:37:35 volumio volumio[807]: info: Sep 05 00:37:35 volumio volumio[807]: ---------------------------- USB Audio Device Attached Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::Close All Modals sent Sep 05 00:37:35 volumio volumio[807]: info: Preparing to save Alsa Options, stopping services first Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioGetState Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioPause Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::pause Sep 05 00:37:35 volumio volumio[807]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Gustard USB Audio 2.0","alsacard":"G20"},"i2s":false} Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:35 volumio volumio[807]: info: Setting mixer Gustard USB Audio 2.0 for card Gustard USB Audio 2.0 Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 05 00:37:35 volumio volumio[807]: info: Updating Volume Controller Parameters: Device: 5 Name: Gustard USB Audio 2.0 Mixer: Gustard USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 05 00:37:35 volumio volumio[807]: info: Disabling external Volume Control Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:35 volumio volumio[807]: info: Preparing to generate the ALSA configuration file Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 00:37:35 volumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:37:35 volumio volumio[807]: info: Asound.conf file unchanged, so no further update is needed Sep 05 00:37:35 volumio volumio[807]: info: Output device has changed, restarting MPD Sep 05 00:37:35 volumio sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 00:37:35 volumio sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:37:35 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:37:35 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:35 volumio sudo[1794]: pam_unix(sudo:session): session closed for user root Sep 05 00:37:35 volumio sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 00:37:35 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 00:37:35 volumio volumio[807]: info: MPD Permissions set Sep 05 00:37:35 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 00:37:35 volumio systemd[1]: mpd.service: Succeeded. Sep 05 00:37:35 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 00:37:36 volumio systemd[1]: Starting Music Player Daemon... Sep 05 00:37:36 volumio sudo[1803]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 00:37:36 volumio sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:37:36 volumio sudo[1803]: pam_unix(sudo:session): session closed for user root Sep 05 00:37:36 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Sep 05 00:37:36 volumio mpd[1805]: Sep 05 00:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 00:37:36 volumio systemd[1]: Started Music Player Daemon. Sep 05 00:37:36 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Sep 05 00:37:36 volumio volumio[807]: error: updateQueue error: null Sep 05 00:37:39 volumio volumio[807]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 05 00:37:39 volumio volumio[807]: info: CoreStateMachine::getcurrentVolume Sep 05 00:37:39 volumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 00:37:39 volumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Sep 05 00:37:39 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:37:39 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:37:39 volumio volumio[807]: info: CoreStateMachine::updateTrackBlock Sep 05 00:37:39 volumio volumio[807]: info: CorePlayQueue::getTrackBlock Sep 05 00:37:39 volumio volumio[807]: info: CoreCommandRouter::volumioRetrievevolume Sep 05 00:37:39 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:37:39 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:37:39 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:37:39 volumio volumio[807]: info: VolumeController:: Volume=100 Mute =false Sep 05 00:37:39 volumio volumio[807]: info: CoreStateMachine::pushState Sep 05 00:37:39 volumio volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 00:37:39 volumio volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 00:37:39 volumio volumio[807]: info: CoreStateMachine::getQueue Sep 05 00:37:39 volumio volumio[807]: info: CorePlayQueue::getQueue Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:48 volumio volumio[807]: info: Retrieving Cloud Streaming UI Sep 05 00:37:48 volumio volumio[807]: info: Getting Tidal Cloud Configuration Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:48 volumio volumio[807]: info: Getting Qobuz Cloud Configuration Sep 05 00:37:48 volumio volumio[807]: info: Asking plugin for UI Config Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:48 volumio volumio[807]: info: Getting Spotify Cloud Configuration Sep 05 00:37:48 volumio volumio[807]: info: Asking plugin for UI Config Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:48 volumio volumio[807]: info: Saving Spotify Acccount Sep 05 00:37:48 volumio volumio[807]: info: Got Tidal Cloud Configuration Sep 05 00:37:48 volumio volumio[807]: info: Got it Sep 05 00:37:48 volumio volumio[807]: info: Got it Sep 05 00:37:48 volumio volumio[807]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 00:37:48 volumio volumio[807]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 00:37:48 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 05 00:37:51 volumio volumio[807]: info: Disabling MyMusic plugin upnp Sep 05 00:37:51 volumio sudo[1828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 05 00:37:51 volumio sudo[1828]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 00:37:51 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 05 00:37:51 volumio volumio[807]: error: Upnp client error: Error: This socket has been ended by the other party Sep 05 00:37:52 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 00:37:53 volumio volumio[807]: info: Enabling MyMusic plugin upnp Sep 05 00:37:53 volumio volumio[807]: info: Enabling plugin upnp Sep 05 00:37:53 volumio volumio[807]: info: Loading plugin "upnp"... Sep 05 00:37:53 volumio volumio[807]: info: [1757018273107] Starting Upmpd Daemon Sep 05 00:37:53 volumio volumio[807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 00:37:53 volumio volumio[807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 00:37:53 volumio volumio[807]: Error: listen EADDRINUSE: address already in use :::6599 Sep 05 00:37:53 volumio volumio[807]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Sep 05 00:37:53 volumio volumio[807]: at listenInCluster (net.js:1366:12) Sep 05 00:37:53 volumio volumio[807]: at Server.listen (net.js:1452:7) Sep 05 00:37:53 volumio volumio[807]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 05 00:37:53 volumio volumio[807]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 05 00:37:53 volumio volumio[807]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 05 00:37:53 volumio volumio[807]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 05 00:37:53 volumio volumio[807]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 05 00:37:53 volumio volumio[807]: code: 'EADDRINUSE', Sep 05 00:37:53 volumio volumio[807]: errno: -98, Sep 05 00:37:53 volumio volumio[807]: syscall: 'listen', Sep 05 00:37:53 volumio volumio[807]: address: '::', Sep 05 00:37:53 volumio volumio[807]: port: 6599 Sep 05 00:37:53 volumio volumio[807]: } Sep 05 00:37:53 volumio volumio[807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 00:37:53 volumio sudo[1842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 00:36 Sep 05 00:37:53 volumio sudo[1842]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"