Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:00 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 20:10:00 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:00 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:00 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:00 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:00 volumio-goco volumio[1174]: info: Listing playlists Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 07 20:10:00 volumio-goco volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 20:10:01 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 07 20:10:01 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:10:01 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:01 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:01 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:01 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:01 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:01 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:01 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:02 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:10:02 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:02 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:02 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:02 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:02 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:02 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:02 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:06 volumio-goco systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 07 20:10:06 volumio-goco systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Dec 07 20:10:06 volumio-goco dbus-daemon[571]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.36' (uid=0 pid=2909 comm="timedatectl show --property=NTPSynchronized --valu") Dec 07 20:10:06 volumio-goco systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 07 20:10:06 volumio-goco dbus-daemon[571]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 07 20:10:06 volumio-goco systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 07 20:10:06 volumio-goco setdatetime-helper.sh[2908]: Time is not synchronized. Attempting to sync... Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioSeek Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::seek Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","service":"mpd","name":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=256&web=Oliver%20Shanti/Medicine%20Power/extralarge&path=%2FINTERNAL%2FMusic%2FWorld%20Music%20-%20New%20Age%20music%2FOliver%20Shanti%2FMedicine%20Power&metadata=false","duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"mp3","channels":2} Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::startPlaybackTimer Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::seek Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: error: null Dec 07 20:10:06 volumio-goco volumio[1174]: info: Dec 07 20:10:06 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:06 volumio-goco volumio[1174]: info: Dec 07 20:10:06 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:06 volumio-goco volumio[1174]: info: Dec 07 20:10:06 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:06 volumio-goco volumio[1174]: info: Dec 07 20:10:06 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand status took 15 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand status took 15 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand status took 15 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand status took 14 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74958,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:06 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74958,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:06 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco sudo[2932]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 07 Dec 2025 19:10:06 GMT#015' Dec 07 20:10:06 volumio-goco sudo[2932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco systemd-journald[465]: Time jumped backwards, rotating. Dec 07 20:10:06 volumio-goco sudo[2932]: pam_unix(sudo:session): session closed for user root Dec 07 20:10:06 volumio-goco setdatetime-helper.sh[2934]: Sun Dec 7 20:10:06 CET 2025 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74958,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:06 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco setdatetime-helper.sh[2908]: Time synchronized successfully. Dec 07 20:10:06 volumio-goco systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Dec 07 20:10:06 volumio-goco systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74958,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:06 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:06 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:06 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:06 volumio-goco volumio[1174]: info: ------------------------------ -533ms Dec 07 20:10:06 volumio-goco volumio[1174]: info: ------------------------------ -535ms Dec 07 20:10:06 volumio-goco volumio[1174]: info: ------------------------------ -534ms Dec 07 20:10:06 volumio-goco volumio[1174]: info: ------------------------------ -532ms Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:06 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 07 20:10:09 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 07 20:10:09 volumio-goco systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 07 20:10:09 volumio-goco systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 07 20:10:09 volumio-goco systemd[1]: apt-daily-upgrade.service: Consumed 1.440s CPU time. Dec 07 20:10:11 volumio-goco volumio[1174]: info: VolumeController::SetAlsaVolume51 Dec 07 20:10:11 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:11 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:11 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:11 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:11 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 50 Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 51 Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:11 volumio-goco volumio[1174]: info: VolumeController::SetAlsaVolume84 Dec 07 20:10:11 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:11 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:11 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:11 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:11 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 84 Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 50 Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 84 Dec 07 20:10:11 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 07 20:10:11 volumio-goco volumio[1174]: info: Setting Spotify Volume from Volumio: 84 Dec 07 20:10:12 volumio-goco volumio[1174]: info: VolumeController::SetAlsaVolume47 Dec 07 20:10:12 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:12 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:12 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:12 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:12 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 84 Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 47 Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 07 20:10:12 volumio-goco volumio[1174]: info: Setting Spotify Volume from Volumio: 47 Dec 07 20:10:12 volumio-goco volumio[1174]: info: VolumeController::SetAlsaVolume46 Dec 07 20:10:12 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:12 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:12 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:12 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:12 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:12 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:14 volumio-goco volumio[1174]: SPOTIFY: SETTING SPOTIFY VOLUME 47 Dec 07 20:10:14 volumio-goco volumio[1174]: info: Sending Spotify command with payload to local API: /player/volume Dec 07 20:10:17 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:10:17 volumio-goco volumio[1174]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 07 20:10:17 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 07 20:10:17 volumio-goco volumio[1174]: info: Received Get System Version Dec 07 20:10:17 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 20:10:17 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:17 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:17 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:17 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:17 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:17 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:17 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:18 volumio-goco volumio[1174]: verbose: New Socket.io Connection to 192.168.31.198:3000 from 192.168.31.24 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 07 20:10:19 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 07 20:10:19 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 07 20:10:20 volumio-goco sudo[3045]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:10:20 volumio-goco sudo[3045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:10:20 volumio-goco sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:10:20 volumio-goco sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:10:20 volumio-goco sudo[3045]: pam_unix(sudo:session): session closed for user root Dec 07 20:10:20 volumio-goco sudo[3046]: pam_unix(sudo:session): session closed for user root Dec 07 20:10:20 volumio-goco volumio[1174]: verbose: New Socket.io Connection to 192.168.31.198 from 192.168.31.24 UA: Mozilla/5.0 (Linux; Android 15; SM-G991B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 07 20:10:20 volumio-goco sudo[3050]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:10:20 volumio-goco sudo[3050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:10:20 volumio-goco sudo[3050]: pam_unix(sudo:session): session closed for user root Dec 07 20:10:20 volumio-goco sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:10:21 volumio-goco sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:10:21 volumio-goco sudo[3052]: pam_unix(sudo:session): session closed for user root Dec 07 20:10:21 volumio-goco volumio[1174]: verbose: New Socket.io Connection to 192.168.31.198 from 192.168.31.24 UA: Mozilla/5.0 (Linux; Android 15; SM-G991B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 07 20:10:21 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:22 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 20:10:22 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:22 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:22 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:22 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:22 volumio-goco volumio[1174]: info: Listing playlists Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 20:10:22 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:10:23 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:23 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:23 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:23 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:23 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:23 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:23 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:24 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 07 20:10:24 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:10:24 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:24 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:24 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:24 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:24 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:24 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:24 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:31 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 07 20:10:35 volumio-goco systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 07 20:10:36 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetQueue Dec 07 20:10:36 volumio-goco volumio[1174]: info: CoreStateMachine::getQueue Dec 07 20:10:36 volumio-goco volumio[1174]: info: CorePlayQueue::getQueue Dec 07 20:10:39 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:10:39 volumio-goco volumio[1174]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 07 20:10:39 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 07 20:10:39 volumio-goco volumio[1174]: info: Received Get System Version Dec 07 20:10:39 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 20:10:39 volumio-goco volumio[1174]: info: Received Get System Info Dec 07 20:10:39 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:10:39 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:10:39 volumio-goco volumio[1174]: info: Discovery: Getting this device information Dec 07 20:10:39 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioGetState Dec 07 20:10:39 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:39 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:10:41 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 07 20:10:41 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 20:10:41 volumio-goco volumio[1174]: info: Preload queue cleared Dec 07 20:10:46 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 07 20:10:46 volumio-goco volumio[1174]: info: TuneIn handleBrowseUri: tunein/local Dec 07 20:10:47 volumio-goco volumio[1174]: info: Preload queue cleared Dec 07 20:10:53 volumio-goco volumio[1174]: info: Preload queue cleared Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::ClearQueue Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::stPlaybackTimer Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::updateTrackBlock Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrackBlock Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::serviceStop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::serviceStop Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::stop Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::clearPlayQueue Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::saveQueue Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushQueue Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::addQueueItems Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::addQueueItems Dec 07 20:10:54 volumio-goco volumio[1174]: info: Preload queue cleared Dec 07 20:10:54 volumio-goco volumio[1174]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s157572 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: info: Dec 07 20:10:54 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand stop took 113 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:54 volumio-goco volumio[1174]: info: Dec 07 20:10:54 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:54 volumio-goco volumio[1174]: info: Dec 07 20:10:54 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:54 volumio-goco volumio[1174]: info: Dec 07 20:10:54 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand status took 14 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand status took 15 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand status took 10 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand status took 7 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: No code Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: No code Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: No code Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow Changes","artist":"Oliver Shanti","album":"Medicine Power","uri":"INTERNAL/Music/World Music - New Age music/Oliver Shanti/Medicine Power/01 Slow Changes.mp3","trackType":"mp3"} Dec 07 20:10:54 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus stop Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: No code Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:54 volumio-goco volumio[1174]: info: ------------------------------ 292ms Dec 07 20:10:54 volumio-goco volumio[1174]: info: ------------------------------ 291ms Dec 07 20:10:54 volumio-goco volumio[1174]: info: ------------------------------ 288ms Dec 07 20:10:54 volumio-goco volumio[1174]: info: ------------------------------ 287ms Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Volumio is not playing Dec 07 20:10:54 volumio-goco volumio[1174]: info: FusionDsp - Clipped samples monitor stopped Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:54 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:54 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushQueue Dec 07 20:10:55 volumio-goco volumio[1174]: info: CorePlayQueue::saveQueue Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::updateTrackBlock Dec 07 20:10:55 volumio-goco volumio[1174]: info: CorePlayQueue::getTrackBlock Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPlay Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::play index 0 Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::stop Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::play index undefined Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 20:10:55 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::startPlaybackTimer Dec 07 20:10:55 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:55 volumio-goco volumio[1174]: info: [1765134655033] ControllerWebradio::clearAddPlayTrack Dec 07 20:10:55 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 20:10:55 volumio-goco volumio[1174]: info: sendMpdCommand stop took 5 milliseconds Dec 07 20:10:55 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: info: sendMpdCommand clear took 5 milliseconds Dec 07 20:10:55 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand load "https://naxidigital-cafe128ssl.streaming.rs:8022/;" Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: error: updateQueue error: null Dec 07 20:10:55 volumio-goco volumio[1174]: error: updateQueue error: null Dec 07 20:10:55 volumio-goco volumio[1174]: error: updateQueue error: null Dec 07 20:10:55 volumio-goco volumio[1174]: error: updateQueue error: null Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 928ms Dec 07 20:10:55 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand add "https://naxidigital-cafe128ssl.streaming.rs:8022/;" Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 928ms Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 927ms Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 920ms Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: info: sendMpdCommand add "https://naxidigital-cafe128ssl.streaming.rs:8022/;" took 6 milliseconds Dec 07 20:10:55 volumio-goco volumio[1174]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 20:10:55 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand play Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Dec 07 20:10:55 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:55 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 12ms Dec 07 20:10:55 volumio-goco volumio[1174]: info: sendMpdCommand play took 12 milliseconds Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 11ms Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 11ms Dec 07 20:10:55 volumio-goco volumio[1174]: info: ------------------------------ 8ms Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 19 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 9 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 5 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 07 20:10:56 volumio-goco volumio[1174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 07 20:10:56 volumio-goco volumio[1174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 56 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 56 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 51 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 10 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 8 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus stop Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:56 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:56 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: ------------------------------ 142ms Dec 07 20:10:56 volumio-goco volumio[1174]: info: ------------------------------ 209ms Dec 07 20:10:56 volumio-goco volumio[1174]: info: ------------------------------ 209ms Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces system playlist update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Ignoring MPD Status Update Dec 07 20:10:56 volumio-goco volumio[1174]: info: Dec 07 20:10:56 volumio-goco volumio[1174]: ---------------------------- MPD announces state update: player Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::getState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 180 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: ------------------------------ 175ms Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand status took 174 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 173 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 173 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 159 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 156 milliseconds Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:56 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:56 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:56 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:56 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1608,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1608,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 597ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 600ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 553ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 552ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 545ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand status took 544 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 543ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand status took 543 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 542ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand status took 543 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 532 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 532 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseState Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1608,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1859,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 836ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 840ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 176 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 176 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: info: sendMpdCommand playlistinfo took 178 milliseconds Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: ControllerMpd::parseTrackInfo Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ControllerMpd::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::servicePushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":";","artist":"NAXI CAFE RADIO (NAXI,Belgrade,Serbia, NAXI,Beograd,Srbija) - 128k","album":null,"uri":"https://naxidigital-cafe128ssl.streaming.rs:8022/;","trackType":"rs:8022/;"} Dec 07 20:10:57 volumio-goco volumio[1174]: verbose: CURRENT POSITION 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState stateService play Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::syncState currentStatus play Dec 07 20:10:57 volumio-goco volumio[1174]: info: Received an update from plugin. extracting info from payload Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CorePlayQueue::getTrack 0 Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreStateMachine::pushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: CoreCommandRouter::volumioPushState Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 877ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 875ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: ------------------------------ 874ms Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: info: FusionDsp - Volumio is playing Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: SPOTIFY VOLUME 47 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: VOLUMIO VOLUME 46 Dec 07 20:10:57 volumio-goco volumio[1174]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:10:58 volumio-goco volumio[1174]: info: FusionDsp - Clipping Monitor started Dec 07 20:11:06 volumio-goco volumio[1174]: Searching plugin music_service/webradio Dec 07 20:11:06 volumio-goco volumio[1174]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 07 20:11:06 volumio-goco volumio[1174]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 20:11:06 volumio-goco volumio[1174]: TypeError: Cannot read properties of undefined (reading 'length') Dec 07 20:11:06 volumio-goco volumio[1174]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Dec 07 20:11:06 volumio-goco volumio[1174]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Dec 07 20:11:06 volumio-goco volumio[1174]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Dec 07 20:11:06 volumio-goco volumio[1174]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 07 20:11:06 volumio-goco volumio[1174]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 20:11:07 volumio-goco sudo[3301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 20:10' Dec 07 20:11:07 volumio-goco sudo[3301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:47:19 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f0f6a90610a0a39b2758a5b3958219e6"