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) Dec 07 20:11:07 volumio-goco sudo[3301]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:07 volumio-goco go-librespot[2440]: time="2025-12-07T20:11:07+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 07 20:11:07 volumio-goco volumio-remote-updater[577]: [2025-12-07 20:11:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 07 20:11:07 volumio-goco volumio-remote-updater[577]: [2025-12-07 20:11:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 07 20:11:07 volumio-goco systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 07 20:11:07 volumio-goco systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Dec 07 20:11:07 volumio-goco dbus-daemon[571]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.38' (uid=0 pid=3318 comm="timedatectl show --property=NTPSynchronized --valu") Dec 07 20:11:07 volumio-goco systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 07 20:11:07 volumio-goco dbus-daemon[571]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 07 20:11:07 volumio-goco systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 07 20:11:07 volumio-goco setdatetime-helper.sh[3314]: Time is not synchronized. Attempting to sync... Dec 07 20:11:07 volumio-goco systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 07 20:11:07 volumio-goco systemd[1]: volumio.service: Consumed 2min 8.224s CPU time. Dec 07 20:11:07 volumio-goco sudo[3326]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 07 Dec 2025 19:11:08 GMT#015' Dec 07 20:11:07 volumio-goco sudo[3326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 20:11:08 volumio-goco setdatetime-helper.sh[3328]: Sun Dec 7 20:11:08 CET 2025 Dec 07 20:11:08 volumio-goco systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 07 20:11:08 volumio-goco sudo[3326]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:08 volumio-goco setdatetime-helper.sh[3314]: Time synchronized successfully. Dec 07 20:11:08 volumio-goco systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Dec 07 20:11:08 volumio-goco systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Dec 07 20:11:08 volumio-goco systemd[1]: dynamicswap.service: Deactivated successfully. Dec 07 20:11:08 volumio-goco systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 07 20:11:08 volumio-goco systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 07 20:11:08 volumio-goco systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 07 20:11:08 volumio-goco systemd[1]: volumio.service: Consumed 2min 8.224s CPU time. Dec 07 20:11:08 volumio-goco systemd[1]: Started volumio.service - Volumio Backend Module. Dec 07 20:11:08 volumio-goco systemd[1]: dynamicswap.service: Deactivated successfully. Dec 07 20:11:09 volumio-goco volumio[3335]: info: ------------------------------------------- Dec 07 20:11:09 volumio-goco volumio[3335]: info: ----- Volumio3 ---- Dec 07 20:11:09 volumio-goco volumio[3335]: info: ------------------------------------------- Dec 07 20:11:09 volumio-goco volumio[3335]: info: ----- System startup ---- Dec 07 20:11:09 volumio-goco volumio[3335]: info: ------------------------------------------- Dec 07 20:11:10 volumio-goco volumio[3335]: info: MYVOLUMIO Environment detected Dec 07 20:11:10 volumio-goco volumio[3335]: info: Plugin folders cleanup Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning into folder /volumio/app/plugins/ Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category audio_interface Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category miscellanea Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category music_service Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category plugins.json Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category system_controller Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category user_interface Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning into folder /data/plugins/ Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category audio_interface Dec 07 20:11:10 volumio-goco volumio[3335]: info: Scanning category music_service Dec 07 20:11:10 volumio-goco volumio[3335]: info: Plugin folders cleanup completed Dec 07 20:11:10 volumio-goco volumio[3335]: info: ------------------------------------------- Dec 07 20:11:10 volumio-goco volumio[3335]: info: ----- Core plugins startup ---- Dec 07 20:11:10 volumio-goco volumio[3335]: info: ------------------------------------------- Dec 07 20:11:10 volumio-goco volumio[3335]: info: Loading plugins from folder /volumio/app/plugins/ Dec 07 20:11:10 volumio-goco volumio[3335]: info: Adding plugin upnp to MyMusic Plugins Dec 07 20:11:10 volumio-goco volumio[3335]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 07 20:11:10 volumio-goco volumio[3335]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 07 20:11:10 volumio-goco volumio[3335]: info: Loading plugins from folder /data/plugins/ Dec 07 20:11:10 volumio-goco volumio[3335]: info: Loading plugin "system"... Dec 07 20:11:10 volumio-goco volumio[3335]: info: Loading plugin "appearance"... Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "network"... Dec 07 20:11:11 volumio-goco volumio[3335]: info: Refreshing Cached IP Addresses Dec 07 20:11:11 volumio-goco sudo[3367]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:11:11 volumio-goco sudo[3367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "services"... Dec 07 20:11:11 volumio-goco sudo[3369]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:11:11 volumio-goco sudo[3367]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:11 volumio-goco sudo[3369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "alsa_controller"... Dec 07 20:11:11 volumio-goco sudo[3369]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:11 volumio-goco sudo[3375]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 07 20:11:11 volumio-goco sudo[3375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:11 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "wizard"... Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "networkfs"... Dec 07 20:11:11 volumio-goco volumio[3335]: info: Starting Udev Watcher for removable devices Dec 07 20:11:11 volumio-goco volumio[3335]: info: Ignoring mount for partition: boot Dec 07 20:11:11 volumio-goco volumio[3335]: info: Ignoring mount for partition: volumio Dec 07 20:11:11 volumio-goco volumio[3335]: info: Ignoring mount for partition: volumio_data Dec 07 20:11:11 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "volumio_command_line_client"... Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "upnp"... Dec 07 20:11:11 volumio-goco volumio[3335]: info: [1765134671822] Starting Upmpd Daemon Dec 07 20:11:11 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "my_music"... Dec 07 20:11:11 volumio-goco volumio[3335]: info: Loading plugin "mpd"... Dec 07 20:11:12 volumio-goco volumio[3335]: info: Loading plugin "upnp_browser"... Dec 07 20:11:12 volumio-goco volumio-remote-updater[577]: [2025-12-07 20:11:12] [connect] Successful connection Dec 07 20:11:13 volumio-goco volumio[3335]: info: Loading plugin "alarm-clock"... Dec 07 20:11:13 volumio-goco volumio[3335]: info: Loading plugin "airplay_emulation"... Dec 07 20:11:13 volumio-goco volumio[3335]: info: Starting Shairport Sync Dec 07 20:11:13 volumio-goco volumio[3335]: info: Loading plugin "last_100"... Dec 07 20:11:13 volumio-goco volumio[3335]: info: Loading plugin "webradio"... Dec 07 20:11:13 volumio-goco volumio[3335]: info: Loading plugin "i2s_dacs"... Dec 07 20:11:13 volumio-goco volumio[3335]: info: I2S DAC not set, start Auto-detection Dec 07 20:11:13 volumio-goco volumio[3335]: info: Loading plugin "volumiodiscovery"... Dec 07 20:11:13 volumio-goco volumio[3335]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 20:11:13 volumio-goco node[3335]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 20:11:13 volumio-goco volumio[3335]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 20:11:13 volumio-goco volumio[3335]: *** WARNING *** For more information see Dec 07 20:11:13 volumio-goco volumio[3335]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 20:11:13 volumio-goco volumio[3335]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 20:11:13 volumio-goco volumio[3335]: *** WARNING *** For more information see Dec 07 20:11:13 volumio-goco node[3335]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 20:11:13 volumio-goco node[3335]: *** WARNING *** For more information see Dec 07 20:11:13 volumio-goco node[3335]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 20:11:13 volumio-goco node[3335]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 20:11:13 volumio-goco node[3335]: *** WARNING *** For more information see Dec 07 20:11:13 volumio-goco volumio[3335]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 07 20:11:13 volumio-goco volumio[3335]: info: Discovery: Started advertising with name: Volumio-Goco Dec 07 20:11:13 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 20:11:13 volumio-goco volumio[3335]: info: Loading plugin "soundcloud"... Dec 07 20:11:14 volumio-goco volumio[3335]: info: Loading plugin "spop"... Dec 07 20:11:14 volumio-goco volumio[3335]: info: Loading plugin "youtube2"... Dec 07 20:11:17 volumio-goco volumio[3335]: info: Loading plugin "outputs"... Dec 07 20:11:17 volumio-goco volumio[3335]: info: Loading plugin "albumart"... Dec 07 20:11:17 volumio-goco volumio[3335]: info: Plugin example_plugin is not enabled Dec 07 20:11:17 volumio-goco volumio[3335]: info: Loading plugin "inputs"... Dec 07 20:11:17 volumio-goco volumio[3335]: info: Loading plugin "updater_comm"... Dec 07 20:11:17 volumio-goco volumio[3335]: info: Plugin mpdemulation is not enabled Dec 07 20:11:17 volumio-goco volumio[3335]: info: Loading plugin "rest_api"... Dec 07 20:11:18 volumio-goco volumio[3335]: info: Loading plugin "websocket"... Dec 07 20:11:18 volumio-goco volumio[3335]: info: Starting Socket.io Server version 1.7.4 Dec 07 20:11:18 volumio-goco volumio[3335]: info: Loading plugin "fusiondsp"... Dec 07 20:11:18 volumio-goco volumio[3408]: Forking 1 albumart workers Dec 07 20:11:18 volumio-goco volumio[3335]: info: Applying required configuration parameters for plugin fusiondsp Dec 07 20:11:18 volumio-goco volumio[3335]: info: Loading i18n strings for locale en Dec 07 20:11:18 volumio-goco volumio[3335]: Updating browse sources language Dec 07 20:11:18 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::initPlayerControls Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3335]: Express server listening on port 3000 Dec 07 20:11:19 volumio-goco volumio[3335]: [Metrics] WebUI: 9s 960.92ms Dec 07 20:11:19 volumio-goco volumio[3335]: info: Setting Device type: x86 Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::resetVolumioState Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::getcurrentVolume Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:19 volumio-goco volumio[3418]: Starting albumart workers Dec 07 20:11:19 volumio-goco volumio[3335]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 07 20:11:19 volumio-goco volumio[3335]: info: Completed loading Core Plugins Dec 07 20:11:19 volumio-goco volumio[3335]: info: Preparing to generate the ALSA configuration file Dec 07 20:11:19 volumio-goco volumio[3335]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 07 20:11:19 volumio-goco volumio[3335]: info: Reading ALSA contributions from plugins. Dec 07 20:11:19 volumio-goco volumio[3335]: info: Volumio Network Manager: Network status updated: 2 Dec 07 20:11:19 volumio-goco volumio[3335]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 07 20:11:19 volumio-goco volumio[3335]: 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: 1 Dec 07 20:11:19 volumio-goco volumio[3335]: 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: 2 Dec 07 20:11:19 volumio-goco volumio[3335]: info: VolumeController:: Volume=45 Mute =false Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::pushState Dec 07 20:11:19 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioPushState Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::updateTrackBlock Dec 07 20:11:19 volumio-goco volumio[3335]: info: CorePlayQueue::getTrackBlock Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 20:11:19 volumio-goco volumio[3335]: info: Reloading queue from file Dec 07 20:11:19 volumio-goco volumio[3335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::setRepeat null single undefined Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::pushState Dec 07 20:11:19 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioPushState Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::setRandom null Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreStateMachine::pushState Dec 07 20:11:19 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:19 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioPushState Dec 07 20:11:19 volumio-goco volumio[3335]: 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: 4 Dec 07 20:11:20 volumio-goco volumio[3335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 07 20:11:20 volumio-goco volumio[3335]: info: VolumeController:: Volume=45 Mute =false Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreStateMachine::pushState Dec 07 20:11:20 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioPushState Dec 07 20:11:20 volumio-goco volumio[3335]: info: Asound.conf file unchanged, so no further update is needed Dec 07 20:11:20 volumio-goco volumio[3335]: info: Output device has changed, restarting MPD Dec 07 20:11:20 volumio-goco volumio[3335]: info: Output device has changed, restarting Shairport Sync Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:20 volumio-goco sudo[3441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 20:11:20 volumio-goco sudo[3441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:20 volumio-goco sudo[3444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 20:11:20 volumio-goco sudo[3441]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:20 volumio-goco sudo[3444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:20 volumio-goco volumio[3335]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 20:11:20 volumio-goco volumio[3335]: info: ___________ START PLUGINS ___________ Dec 07 20:11:20 volumio-goco volumio[3335]: info: ControllerMpd::onStart: Initializing MPD Dec 07 20:11:20 volumio-goco volumio[3335]: info: Creating MPD Configuration file Dec 07 20:11:20 volumio-goco systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 20:11:20 volumio-goco volumio[3335]: info: [1765134680629] CoreMusicLibrary::Adding element Media Servers Dec 07 20:11:20 volumio-goco sudo[3457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:20 volumio-goco sudo[3457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:20 volumio-goco sudo[3457]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 20:11:20 volumio-goco volumio[3335]: info: [1765134680770] CoreMusicLibrary::Adding element Last_100 Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 20:11:20 volumio-goco volumio[3335]: info: [1765134680781] CoreMusicLibrary::Adding element Webradio Dec 07 20:11:20 volumio-goco sudo[3460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 20:11:20 volumio-goco sudo[3460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:20 volumio-goco volumio[3335]: info: Initializing BBC Radios Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 20:11:20 volumio-goco volumio[3335]: info: [1765134680943] CoreMusicLibrary::Adding element SoundCloud Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:20 volumio-goco volumio[3335]: Cannot find translation for source SoundCloud Dec 07 20:11:20 volumio-goco volumio[3335]: info: Creating Spotify config file Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 20:11:20 volumio-goco volumio[3335]: info: [1765134680988] CoreMusicLibrary::Adding element YouTube2 Dec 07 20:11:20 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:20 volumio-goco volumio[3335]: Cannot find translation for source SoundCloud Dec 07 20:11:20 volumio-goco volumio[3335]: Cannot find translation for source YouTube2 Dec 07 20:11:21 volumio-goco volumio[3335]: info: Loading i18n strings for locale en Dec 07 20:11:21 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 07 20:11:21 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:21 volumio-goco volumio[3335]: info: FusionDsp - mixtype--------------------- Hardware Dec 07 20:11:21 volumio-goco systemd[1]: mpd.service: Deactivated successfully. Dec 07 20:11:21 volumio-goco volumio[3335]: info: Volumio Calling Home Dec 07 20:11:21 volumio-goco systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 07 20:11:21 volumio-goco systemd[1]: mpd.service: Consumed 3.008s CPU time. Dec 07 20:11:21 volumio-goco systemd[1]: mpd.socket: Deactivated successfully. Dec 07 20:11:21 volumio-goco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 07 20:11:21 volumio-goco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 07 20:11:21 volumio-goco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 07 20:11:21 volumio-goco systemd[1]: Starting mpd.service - Music Player Daemon... Dec 07 20:11:21 volumio-goco volumio[3335]: info: Preparing to generate the ALSA configuration file Dec 07 20:11:21 volumio-goco sudo[3488]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 07 20:11:21 volumio-goco sudo[3488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 20:11:21 volumio-goco sudo[3488]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:21 volumio-goco volumio[3335]: 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: 6 Dec 07 20:11:21 volumio-goco sudo[3375]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:22 volumio-goco volumio[3335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 07 20:11:22 volumio-goco volumio[3335]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 07 20:11:22 volumio-goco sudo[3508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:11:22 volumio-goco volumio[3335]: info: Reading ALSA contributions from plugins. Dec 07 20:11:22 volumio-goco sudo[3508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:22 volumio-goco sudo[3510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:11:22 volumio-goco sudo[3508]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:22 volumio-goco sudo[3510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:22 volumio-goco sudo[3510]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:22 volumio-goco volumio[3335]: info: MPD Permissions set Dec 07 20:11:22 volumio-goco volumio[3335]: info: MPD Permissions set Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:22 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:22 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:22 volumio-goco volumio[3335]: error: Upnp client error: Error: This socket has been ended by the other party Dec 07 20:11:22 volumio-goco volumio[3335]: info: Spotify config file written Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco sudo[3514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 07 20:11:22 volumio-goco sudo[3514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 07 20:11:22 volumio-goco systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 07 20:11:22 volumio-goco systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 07 20:11:22 volumio-goco systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 07 20:11:22 volumio-goco systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 07 20:11:22 volumio-goco go-librespot[3523]: go-librespot daemon starting... Dec 07 20:11:22 volumio-goco sudo[3514]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:22+01:00" level=info msg="running go-librespot 0.3.2" Dec 07 20:11:22 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:22+01:00" level=debug msg="app state loaded" Dec 07 20:11:22 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:22+01:00" level=debug msg="stored credentials not found" Dec 07 20:11:22 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:22 volumio-goco volumio[3335]: info: No need to fix Spotify hosts Dec 07 20:11:22 volumio-goco volumio[3335]: 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: 8 Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 07 20:11:22 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 07 20:11:22 volumio-goco volumio[3335]: info: Starting Shairport Sync Dec 07 20:11:22 volumio-goco volumio[3335]: info: Starting Shairport Sync Dec 07 20:11:22 volumio-goco volumio[3335]: info: Starting Shairport Sync Dec 07 20:11:22 volumio-goco sudo[3540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 20:11:22 volumio-goco sudo[3540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:23 volumio-goco sudo[3542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 20:11:23 volumio-goco sudo[3542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:23 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 07 20:11:23 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 07 20:11:23 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 07 20:11:23 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:23+01:00" level=info msg="zeroconf server listening on port 35785" Dec 07 20:11:23 volumio-goco sudo[3545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 20:11:23 volumio-goco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 07 20:11:23 volumio-goco systemd[1]: shairport-sync.service: Deactivated successfully. Dec 07 20:11:23 volumio-goco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:23 volumio-goco systemd[1]: shairport-sync.service: Consumed 1.550s CPU time. Dec 07 20:11:23 volumio-goco sudo[3545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:23 volumio-goco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:23 volumio-goco sudo[3540]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:23 volumio-goco sudo[3548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 07 20:11:23 volumio-goco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 07 20:11:23 volumio-goco systemd[1]: shairport-sync.service: Deactivated successfully. Dec 07 20:11:23 volumio-goco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:23 volumio-goco sudo[3548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:23 volumio-goco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:23 volumio-goco sudo[3542]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:23 volumio-goco volumio[3335]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 07 20:11:23 volumio-goco volumio[3335]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Dec 07 20:11:23 volumio-goco volumio[3335]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 07 20:11:23 volumio-goco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 07 20:11:23 volumio-goco systemd[1]: shairport-sync.service: Deactivated successfully. Dec 07 20:11:23 volumio-goco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:23 volumio-goco volumio[3335]: info: camilladsp service started and running in background, instance 1 Dec 07 20:11:23 volumio-goco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:23 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:23 volumio-goco sudo[3545]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:23 volumio-goco sudo[3548]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:23 volumio-goco volumio[3335]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 <-AAAAAAAAAAAAA Dec 07 20:11:23 volumio-goco volumio[3335]: info: FusionDsp loaded Dec 07 20:11:23 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:23 volumio-goco volumio[3335]: error: FusionDsp - Socket connection not established Dec 07 20:11:23 volumio-goco volumio[3335]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 07 20:11:23 volumio-goco volumio[3335]: info: Adding Signal Path Element [object Object] Dec 07 20:11:23 volumio-goco volumio[3335]: info: Adding fusiondspeq DSP Signal Path Element Dec 07 20:11:23 volumio-goco volumio[3335]: info: FusionDsp - ---- installed callbackRead Dec 07 20:11:23 volumio-goco volumio[3335]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 07 20:11:23 volumio-goco sudo[3581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 07 20:11:23 volumio-goco sudo[3581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:23 volumio-goco sudo[3581]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:23 volumio-goco volumio[3335]: info: Upmpdcli Daemon Started Dec 07 20:11:23 volumio-goco volumio[3335]: info: Shairport-Sync Started Dec 07 20:11:23 volumio-goco volumio[3335]: Error adding Membership: Error: addMembership EINVAL Dec 07 20:11:23 volumio-goco volumio[3335]: info: Shairport-Sync Started Dec 07 20:11:23 volumio-goco volumio[3335]: info: Shairport-Sync Started Dec 07 20:11:23 volumio-goco volumio[3335]: info: Asound.conf file unchanged, so no further update is needed Dec 07 20:11:23 volumio-goco volumio[3335]: info: Output device has changed, restarting MPD Dec 07 20:11:24 volumio-goco volumio[3335]: info: Output device has changed, restarting Shairport Sync Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:24 volumio-goco sudo[3587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 07 20:11:24 volumio-goco sudo[3587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:24 volumio-goco sudo[3588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 07 20:11:24 volumio-goco sudo[3588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:24 volumio-goco volumio[3335]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 07 20:11:24 volumio-goco sudo[3587]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:24 volumio-goco volumio[3335]: 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: 8 Dec 07 20:11:24 volumio-goco systemd[1]: mpd.service: Deactivated successfully. Dec 07 20:11:24 volumio-goco systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 07 20:11:24 volumio-goco systemd[1]: mpd.socket: Deactivated successfully. Dec 07 20:11:24 volumio-goco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 07 20:11:24 volumio-goco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 07 20:11:24 volumio-goco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 07 20:11:24 volumio-goco volumio[3335]: info: MPD Permissions set Dec 07 20:11:24 volumio-goco systemd[1]: Starting mpd.service - Music Player Daemon... Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 07 20:11:24 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 20:11:24 volumio-goco sudo[3596]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 07 20:11:24 volumio-goco sudo[3596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 07 20:11:24 volumio-goco volumio[3335]: 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: 9 Dec 07 20:11:24 volumio-goco volumio[3335]: info: Starting Shairport Sync Dec 07 20:11:24 volumio-goco sudo[3596]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:24 volumio-goco sudo[3609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 07 20:11:24 volumio-goco sudo[3609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:25 volumio-goco volumio-remote-updater[577]: [2025-12-07 20:11:25] [connect] Successful connection Dec 07 20:11:25 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 20:11:25 volumio-goco kernel: perf: interrupt took too long (4948 > 4943), lowering kernel.perf_event_max_sample_rate to 40250 Dec 07 20:11:25 volumio-goco sudo[3611]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:11:25 volumio-goco sudo[3611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:25 volumio-goco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 07 20:11:25 volumio-goco systemd[1]: shairport-sync.service: Deactivated successfully. Dec 07 20:11:25 volumio-goco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:25 volumio-goco sudo[3614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:11:25 volumio-goco sudo[3614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:25 volumio-goco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 07 20:11:25 volumio-goco sudo[3611]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:25 volumio-goco sudo[3609]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:25 volumio-goco sudo[3614]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:25 volumio-goco volumio[3335]: info: Discovery: adding fc424e36-b5bc-4e66-b049-6ea7ce0c51c4 Dec 07 20:11:25 volumio-goco volumio[3335]: info: Discovery: Found device Volumio-Goco Dec 07 20:11:25 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:25 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:25 volumio-goco volumio[3335]: info: Discovery: this is already registered, fc424e36-b5bc-4e66-b049-6ea7ce0c51c4 Dec 07 20:11:25 volumio-goco volumio[3335]: info: Discovery: Found device Volumio-Goco Dec 07 20:11:25 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:25 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:25 volumio-goco volumio[3335]: info: Shairport-Sync Started Dec 07 20:11:25 volumio-goco volumio-remote-updater[577]: [2025-12-07 20:11:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765134685 101 Dec 07 20:11:25 volumio-goco volumio[3335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 10 Dec 07 20:11:25 volumio-goco volumio[3335]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 07 20:11:25 volumio-goco volumio[3335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 07 20:11:25 volumio-goco volumio[3335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 07 20:11:25 volumio-goco volumio[3335]: 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: 13 Dec 07 20:11:25 volumio-goco volumio[3335]: info: go-librespot daemon successfully initialized Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:26 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 20:11:26 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:26 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:26 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:26 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:26 volumio-goco volumio[3335]: info: Listing playlists Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:11:26 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:26 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:26 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:26 volumio-goco volumio[3335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 07 20:11:26 volumio-goco volumio[3335]: info: Volumio called home Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 07 20:11:26 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:26 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 07 20:11:27 volumio-goco sudo[3636]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 07 20:11:27 volumio-goco sudo[3636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3636]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco sudo[3642]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 07 20:11:27 volumio-goco sudo[3642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3648]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 07 20:11:27 volumio-goco sudo[3648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3642]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 20:11:27 volumio-goco sudo[3653]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 07 20:11:27 volumio-goco sudo[3648]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco sudo[3653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3658]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:11:27 volumio-goco sudo[3653]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco sudo[3660]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:11:27 volumio-goco sudo[3660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3660]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco sudo[3658]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco sudo[3669]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:11:27 volumio-goco sudo[3671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:11:27 volumio-goco sudo[3669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:27 volumio-goco sudo[3669]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco sudo[3671]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:27 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:27 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:27 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:27 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:27 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:27 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:27 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:27 volumio-goco volumio[3335]: 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: 14 Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 20:11:28 volumio-goco mpd[3607]: 2025-12-07T20:11:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 07 20:11:28 volumio-goco volumio[3335]: 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: 15 Dec 07 20:11:28 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:28 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:28 volumio-goco systemd[1]: Started mpd.service - Music Player Daemon. Dec 07 20:11:28 volumio-goco sudo[3444]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:28 volumio-goco sudo[3460]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:28 volumio-goco sudo[3588]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:28 volumio-goco volumio[3335]: info: Listing playlists Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetQueue Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreStateMachine::getQueue Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getQueue Dec 07 20:11:28 volumio-goco volumio[3335]: info: Listing playlists Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 20:11:28 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:28 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:28 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:11:28 volumio-goco volumio[3335]: info: Completed starting Core Plugins Dec 07 20:11:28 volumio-goco volumio[3335]: info: ------------------------------------------- Dec 07 20:11:28 volumio-goco volumio[3335]: info: ----- MyVolumio plugins startup ---- Dec 07 20:11:28 volumio-goco volumio[3335]: info: ------------------------------------------- Dec 07 20:11:28 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 07 20:11:28 volumio-goco volumio[3335]: error: MPD error: The expression evaluated to a falsy value: Dec 07 20:11:28 volumio-goco volumio[3335]: assert.ok(self.idling) Dec 07 20:11:28 volumio-goco volumio[3335]: error: The expression evaluated to a falsy value: Dec 07 20:11:28 volumio-goco volumio[3335]: assert.ok(self.idling) Dec 07 20:11:28 volumio-goco volumio[3335]: error: MPD error: The expression evaluated to a falsy value: Dec 07 20:11:28 volumio-goco volumio[3335]: assert.ok(self.idling) Dec 07 20:11:28 volumio-goco volumio[3335]: error: The expression evaluated to a falsy value: Dec 07 20:11:28 volumio-goco volumio[3335]: assert.ok(self.idling) Dec 07 20:11:28 volumio-goco volumio[3335]: info: MPD running with PID3607 Dec 07 20:11:28 volumio-goco volumio[3335]: ,establishing connection Dec 07 20:11:28 volumio-goco volumio[3335]: error: updateQueue error: null Dec 07 20:11:28 volumio-goco volumio[3335]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 20:11:28 volumio-goco volumio[3335]: error: updateQueue error: null Dec 07 20:11:29 volumio-goco volumio[3335]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 20:11:29 volumio-goco volumio[3335]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 20:11:29 volumio-goco volumio[3335]: info: Initializing connection to go-librespot Websocket Dec 07 20:11:29 volumio-goco go-librespot[3525]: time="2025-12-07T20:11:29+01:00" level=debug msg="new websocket client" Dec 07 20:11:29 volumio-goco volumio[3335]: info: Connection to go-librespot Websocket established Dec 07 20:11:29 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:11:29 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:29 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:29 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:29 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:29 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:29 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:29 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:30 volumio-goco sudo[3684]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:11:30 volumio-goco sudo[3684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:30 volumio-goco sudo[3686]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:11:30 volumio-goco sudo[3684]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:30 volumio-goco sudo[3686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:30 volumio-goco sudo[3686]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:30 volumio-goco volumio[3335]: 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: 15 Dec 07 20:11:30 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:11:30 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:30 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:30 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:30 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:30 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:30 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:30 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:30 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 07 20:11:30 volumio-goco sudo[3691]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 07 20:11:30 volumio-goco sudo[3691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:30 volumio-goco sudo[3693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 20:11:30 volumio-goco sudo[3693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:30 volumio-goco sudo[3691]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:30 volumio-goco sudo[3693]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:30 volumio-goco volumio[3335]: 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: 16 Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:31 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetQueue Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreStateMachine::getQueue Dec 07 20:11:31 volumio-goco volumio[3335]: info: CorePlayQueue::getQueue Dec 07 20:11:31 volumio-goco volumio[3335]: info: Listing playlists Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 20:11:31 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:31 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:31 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:31 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 07 20:11:31 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 07 20:11:32 volumio-goco volumio[3335]: info: Getting Spotify volume Dec 07 20:11:32 volumio-goco volumio[3335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:32 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:32 volumio-goco volumio[3335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Dec 07 20:11:32 volumio-goco volumio[3335]: SPOTIFY: SPOTIFY VOLUME undefined Dec 07 20:11:32 volumio-goco volumio[3335]: SPOTIFY: VOLUMIO VOLUME 45 Dec 07 20:11:32 volumio-goco volumio[3335]: info: Aligning Spotify Volume to Volumio Volume Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:32 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:32 volumio-goco volumio[3335]: info: Setting Spotify Volume from Volumio: 45 Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:11:32 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:32 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:32 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:32 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 07 20:11:33 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 07 20:11:33 volumio-goco volumio[3335]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 07 20:11:33 volumio-goco volumio[3335]: Cannot compose Albumart path Dec 07 20:11:33 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 20:11:33 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:33 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:33 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:33 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:33 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:33 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:33 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:34 volumio-goco volumio[3335]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Dec 07 20:11:34 volumio-goco volumio[3335]: info: Sending Spotify command with payload to local API: /player/volume Dec 07 20:11:34 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 07 20:11:34 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 07 20:11:34 volumio-goco volumio[3335]: info: TuneIn handleBrowseUri: tunein/local Dec 07 20:11:35 volumio-goco volumio[3335]: error: Cannot read properties of undefined (reading 'indexOf') Dec 07 20:11:35 volumio-goco volumio[3335]: error: Failed to execute browseSource: Error: Cannot list category items for local: TypeError: Cannot read properties of undefined (reading 'indexOf') Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 07 20:11:36 volumio-goco volumio[3335]: info: Adding plugin bluetooth to MyMusic Plugins Dec 07 20:11:36 volumio-goco volumio[3335]: info: Adding plugin multiroom to MyMusic Plugins Dec 07 20:11:36 volumio-goco volumio[3335]: info: Adding plugin metavolumio to MyMusic Plugins Dec 07 20:11:36 volumio-goco volumio[3335]: info: Adding plugin cd_controller to MyMusic Plugins Dec 07 20:11:36 volumio-goco volumio[3335]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 07 20:11:36 volumio-goco volumio[3335]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 07 20:11:36 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 07 20:11:37 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 07 20:11:37 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 07 20:11:37 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:37 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:37 volumio-goco volumio[3335]: info: Starting MyVolumio Remote Streaming Endpoints Dec 07 20:11:37 volumio-goco volumio[3335]: info: MyVolumio login type: Token Dec 07 20:11:37 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 07 20:11:37 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 07 20:11:37 volumio-goco systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 07 20:11:38 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 07 20:11:38 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 07 20:11:38 volumio-goco volumio[3335]: info: Streaming services startup Dec 07 20:11:38 volumio-goco volumio[3335]: info: Starting Streaming Daemon Dec 07 20:11:38 volumio-goco sudo[3732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 20:11:38 volumio-goco sudo[3732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:38 volumio-goco volumio[3335]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 07 20:11:38 volumio-goco sudo[3732]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:38 volumio-goco volumio[3335]: error: Cannot start Volumio Streaming Daemon Dec 07 20:11:38 volumio-goco volumio[3335]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 07 20:11:38 volumio-goco volumio[3335]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 07 20:11:38 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 07 20:11:38 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 07 20:11:38 volumio-goco volumio[3335]: info: TuneIn handleBrowseUri: tunein/local Dec 07 20:11:39 volumio-goco volumio[3335]: info: Preload queue cleared Dec 07 20:11:39 volumio-goco volumio[3335]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 07 20:11:39 volumio-goco volumio[3335]: info: MyVolumio token set successfully Dec 07 20:11:39 volumio-goco volumio[3335]: info: MYVOLUMIO: Adding device Dec 07 20:11:39 volumio-goco volumio[3335]: info: MYVOLUMIO: Evaluating Server Dec 07 20:11:40 volumio-goco volumio[3335]: info: MyVolumio status changed Dec 07 20:11:40 volumio-goco volumio[3335]: info: Streaming services startup Dec 07 20:11:40 volumio-goco volumio[3335]: info: Starting Streaming Daemon Dec 07 20:11:40 volumio-goco volumio[3335]: info: Removing browser output: myVolumio user plan is not superstar Dec 07 20:11:40 volumio-goco volumio[3335]: info: Removing audio output: Dec 07 20:11:40 volumio-goco volumio[3335]: info: Stoppping Tunnel 1 Dec 07 20:11:40 volumio-goco sudo[3765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 20:11:40 volumio-goco sudo[3765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:40 volumio-goco sudo[3767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 07 20:11:40 volumio-goco sudo[3767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 20:11:40 volumio-goco sudo[3765]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:40 volumio-goco volumio[3335]: error: Cannot start Volumio Streaming Daemon Dec 07 20:11:40 volumio-goco volumio[3335]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 07 20:11:40 volumio-goco volumio[3335]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 07 20:11:40 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 20:11:40 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 20:11:40 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 20:11:40 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 20:11:40 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 20:11:40 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 07 20:11:40 volumio-goco sudo[3767]: pam_unix(sudo:session): session closed for user root Dec 07 20:11:40 volumio-goco volumio[3335]: info: Remote SSH Stopped Dec 07 20:11:40 volumio-goco volumio[3335]: info: Setting Geolocation for MyVolumio to eu5 Dec 07 20:11:40 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:40 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:40 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:40 volumio-goco volumio[3335]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 07 20:11:40 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 07 20:11:41 volumio-goco volumio[3335]: info: Updating MyVolumio device info Dec 07 20:11:41 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:41 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:41 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:42 volumio-goco volumio[3335]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 07 20:11:44 volumio-goco volumio[3335]: info: MYVOLUMIO: Adding device Dec 07 20:11:44 volumio-goco volumio[3335]: info: MYVOLUMIO: Evaluating Server Dec 07 20:11:45 volumio-goco volumio[3335]: info: Setting Geolocation for MyVolumio to eu6 Dec 07 20:11:45 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:45 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:45 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:45 volumio-goco volumio[3335]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 07 20:11:46 volumio-goco volumio[3335]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 07 20:11:46 volumio-goco volumio[3335]: info: Received Get System Version Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 20:11:46 volumio-goco volumio[3335]: info: Received Get System Info Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 07 20:11:46 volumio-goco volumio[3335]: info: Discovery: Getting this device information Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::volumioGetState Dec 07 20:11:46 volumio-goco volumio[3335]: info: CorePlayQueue::getTrack 0 Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 07 20:11:46 volumio-goco volumio[3335]: info: Updating MyVolumio device info Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:46 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 20:11:46 volumio-goco volumio[3335]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 07 20:11:47 volumio-goco volumio[3335]: Searching plugin music_service/webradio Dec 07 20:11:47 volumio-goco volumio[3335]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 07 20:11:48 volumio-goco volumio[3335]: Use XMLElement.setAttribute instead Dec 07 20:11:48 volumio-goco volumio[3335]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 20:11:48 volumio-goco volumio[3335]: TypeError: children[i].attr(...).value is not a function Dec 07 20:11:48 volumio-goco volumio[3335]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Dec 07 20:11:48 volumio-goco volumio[3335]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 07 20:11:48 volumio-goco volumio[3335]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 20:11:48 volumio-goco sudo[3840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 20:10' Dec 07 20:11:48 volumio-goco sudo[3840]: 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"