Jun 16 13:59:23 servicedisco go-librespot[1450]: time="2025-06-16T13:59:23+03:00" level=trace msg="sent dealer ping" Jun 16 13:59:23 servicedisco go-librespot[1450]: time="2025-06-16T13:59:23+03:00" level=trace msg="received dealer pong" Jun 16 13:59:24 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 13:59:24 servicedisco volumio[964]: info: Received Get System Info Jun 16 13:59:24 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 13:59:24 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 13:59:24 servicedisco volumio[964]: info: Discovery: Getting this device information Jun 16 13:59:24 servicedisco volumio[964]: info: CoreCommandRouter::volumioGetState Jun 16 13:59:24 servicedisco volumio[964]: info: CorePlayQueue::getTrack 29 Jun 16 13:59:24 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 13:59:25 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 16 13:59:25 servicedisco volumio[964]: info: Preload queue cleared Jun 16 13:59:28 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 16 13:59:28 servicedisco volumio[964]: info: TuneIn handleBrowseUri: tunein/local Jun 16 13:59:29 servicedisco volumio[964]: info: Preload queue cleared Jun 16 13:59:34 servicedisco systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 16 13:59:34 servicedisco dbus-daemon[656]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.433' (uid=0 pid=66909 comm="timedatectl show --property=NTPSynchronized --valu") Jun 16 13:59:34 servicedisco systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 16 13:59:34 servicedisco dbus-daemon[656]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 16 13:59:34 servicedisco systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 16 13:59:34 servicedisco setdatetime-helper.sh[66908]: Time is already synchronized. Jun 16 13:59:34 servicedisco volumio[964]: info: Preload queue cleared Jun 16 13:59:34 servicedisco volumio[964]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 16 13:59:34 servicedisco systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::ClearQueue Jun 16 13:59:34 servicedisco systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::stop Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:34 servicedisco volumio[964]: info: CorePlayQueue::clearPlayQueue Jun 16 13:59:34 servicedisco volumio[964]: info: CorePlayQueue::saveQueue Jun 16 13:59:34 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushQueue Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::addQueueItems Jun 16 13:59:34 servicedisco volumio[964]: info: CorePlayQueue::addQueueItems Jun 16 13:59:34 servicedisco volumio[964]: info: Preload queue cleared Jun 16 13:59:34 servicedisco volumio[964]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s80307 Jun 16 13:59:34 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 16 13:59:34 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushQueue Jun 16 13:59:34 servicedisco volumio[964]: info: CorePlayQueue::saveQueue Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::updateTrackBlock Jun 16 13:59:34 servicedisco volumio[964]: info: CorePlayQueue::getTrackBlock Jun 16 13:59:34 servicedisco volumio[964]: info: CoreCommandRouter::volumioPlay Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::play index 0 Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::stop Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::play index undefined Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:34 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:34 servicedisco volumio[964]: info: CoreStateMachine::startPlaybackTimer Jun 16 13:59:34 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:34 servicedisco volumio[964]: info: [1750071574996] ControllerWebradio::clearAddPlayTrack Jun 16 13:59:34 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand stop took 5 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand clear Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand clear took 2 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand load "http://94.23.0.114:41972" Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand add "http://94.23.0.114:41972" Jun 16 13:59:35 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:35 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:35 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:35 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 191ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 189ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 188ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 185ms Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand add "http://94.23.0.114:41972" took 4 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand play Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand play took 9 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 8ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 7ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 5ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 3ms Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 17 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 16 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 13 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 12 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 9 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 16 13:59:35 servicedisco volumio[964]: info: camilladsp stopping service pid 60421... Jun 16 13:59:35 servicedisco volumio[964]: grep: /proc/60421/cmdline: binary file matches Jun 16 13:59:35 servicedisco volumio[964]: info: camilladsp service terminated, instance 1 Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 16 13:59:35 servicedisco volumio[964]: info: camilladsp service started and running in background, instance 1 Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 152 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 149 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 149 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 149 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 148 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 149 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 9ms Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":null,"album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus stop Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":null,"album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":null,"album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":null,"album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":null,"album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 224ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 266ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 265ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 265ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 263ms Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 118 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 116ms Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 112 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 111 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 13 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 12 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 296ms Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:35 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:35 servicedisco volumio[964]: info: Jun 16 13:59:35 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 125 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 125 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 124 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 124 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 101ms Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 100 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 99ms Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand status took 106 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 335ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 332ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 231ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 230ms Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 89 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 89 milliseconds Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:35 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:35 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:35 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 233ms Jun 16 13:59:35 servicedisco volumio[964]: info: ------------------------------ 231ms Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:35 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:36 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:37 servicedisco volumio[964]: info: VolumeController::SetAlsaVolume25 Jun 16 13:59:37 servicedisco volumio[964]: info: CoreCommandRouter::volumioGetState Jun 16 13:59:37 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:37 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:37 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:37 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:37 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:37 servicedisco volumio[964]: SPOTIFY: SPOTIFY VOLUME 20 Jun 16 13:59:37 servicedisco volumio[964]: SPOTIFY: VOLUMIO VOLUME 25 Jun 16 13:59:37 servicedisco volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 16 13:59:37 servicedisco volumio[964]: info: Setting Spotify Volume from Volumio: 25 Jun 16 13:59:38 servicedisco volumio[964]: SPOTIFY: SETTING SPOTIFY VOLUME 25 Jun 16 13:59:38 servicedisco volumio[964]: info: Sending Spotify command with payload to local API: /player/volume Jun 16 13:59:38 servicedisco go-librespot[1450]: time="2025-06-16T13:59:38+03:00" level=debug msg="update volume to 16383/65535" Jun 16 13:59:39 servicedisco go-librespot[1450]: time="2025-06-16T13:59:39+03:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 16 13:59:39 servicedisco go-librespot[1450]: time="2025-06-16T13:59:39+03:00" level=trace msg="emitting websocket event: volume" Jun 16 13:59:39 servicedisco volumio[964]: SPOTIFY: received: {"type":"volume","data":{"value":25,"max":100}} Jun 16 13:59:39 servicedisco volumio[964]: SPOTIFY: RECEIVED SPOTIFY VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: info: Preload queue cleared Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::ClearQueue Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::stPlaybackTimer Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::updateTrackBlock Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrackBlock Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::serviceStop Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::serviceStop Jun 16 13:59:42 servicedisco volumio[964]: info: [1750071582334] ControllerWebradio::stop Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::clearPlayQueue Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::saveQueue Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushQueue Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::addQueueItems Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::addQueueItems Jun 16 13:59:42 servicedisco volumio[964]: info: Preload queue cleared Jun 16 13:59:42 servicedisco volumio[964]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s79361 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand stop took 51 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand status took 11 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand status took 8 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand status took 7 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand status took 4 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:42 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: No code Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:42 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: No code Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:42 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: No code Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: 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":"94.23.0.114:41972","artist":"No Name","album":null,"uri":"http://94.23.0.114:41972","trackType":"114:41972"} Jun 16 13:59:42 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: No code Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:42 servicedisco volumio[964]: info: ------------------------------ 101ms Jun 16 13:59:42 servicedisco volumio[964]: info: ------------------------------ 99ms Jun 16 13:59:42 servicedisco volumio[964]: info: ------------------------------ 99ms Jun 16 13:59:42 servicedisco volumio[964]: info: ------------------------------ 98ms Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Volumio is not playing Jun 16 13:59:42 servicedisco volumio[964]: info: FusionDsp - Clipped samples monitor stopped Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushQueue Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::saveQueue Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::updateTrackBlock Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrackBlock Jun 16 13:59:42 servicedisco volumio[964]: info: CoreCommandRouter::volumioPlay Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::play index 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::stop Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::play index undefined Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: CoreStateMachine::startPlaybackTimer Jun 16 13:59:42 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:42 servicedisco volumio[964]: info: [1750071582938] ControllerWebradio::clearAddPlayTrack Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand stop took 3 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand clear Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:42 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:42 servicedisco volumio[964]: info: sendMpdCommand clear took 2 milliseconds Jun 16 13:59:42 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radiojar.com/9ndpdg3c0s8uv" Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:42 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:42 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:42 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:42 servicedisco volumio[964]: info: ------------------------------ 6ms Jun 16 13:59:42 servicedisco volumio[964]: info: Jun 16 13:59:42 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:42 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radiojar.com/9ndpdg3c0s8uv" Jun 16 13:59:43 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:43 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:43 servicedisco volumio[964]: error: updateQueue error: null Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 516ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 515ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 513ms Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand add "http://stream.radiojar.com/9ndpdg3c0s8uv" took 2 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand play Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:43 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand play took 2 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:43 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:43 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:43 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 6ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 4ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 3ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 2ms Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 15 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 18 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 17 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 16 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: Jun 16 13:59:43 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 16 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 6 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 6 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand status took 5 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus stop Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:43 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:43 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:43 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 59ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 81ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 80ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 79ms Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 52 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 50 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 50 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 50 milliseconds Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:43 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:43 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:43 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: 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":"9ndpdg3c0s8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:43 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:43 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:43 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 139ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 129ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 129ms Jun 16 13:59:43 servicedisco volumio[964]: info: ------------------------------ 128ms Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:43 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:44 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:44 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:44 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces system playlist update Jun 16 13:59:44 servicedisco volumio[964]: info: Ignoring MPD Status Update Jun 16 13:59:44 servicedisco volumio[964]: info: Jun 16 13:59:44 servicedisco volumio[964]: ---------------------------- MPD announces state update: player Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::getState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand status Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 16ms Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand status took 16 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 14ms Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand status took 12 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 12ms Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand status took 11 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 10ms Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand status took 10 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseState Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Clipping Monitor started Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:44 servicedisco volumio[964]: verbose: ControllerMpd::parseTrackInfo Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:44 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:44 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jellybean & Jovonn Present Hos - You Took My Love (Jovonn Next Moov Club Mix)","artist":"9ndpdg3c0s8uv","album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:44 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:44 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:44 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:44 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jellybean & Jovonn Present Hos - You Took My Love (Jovonn Next Moov Club Mix)","artist":"9ndpdg3c0s8uv","album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:44 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:44 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:44 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:44 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jellybean & Jovonn Present Hos - You Took My Love (Jovonn Next Moov Club Mix)","artist":"9ndpdg3c0s8uv","album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:44 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:44 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: ControllerMpd::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::servicePushState Jun 16 13:59:44 servicedisco volumio[964]: info: CorePlayQueue::getTrack 0 Jun 16 13:59:44 servicedisco volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jellybean & Jovonn Present Hos - You Took My Love (Jovonn Next Moov Club Mix)","artist":"9ndpdg3c0s8uv","album":null,"uri":"http://stream.radiojar.com/9ndpdg3c0s8uv","trackType":""} Jun 16 13:59:44 servicedisco volumio[964]: verbose: CURRENT POSITION 0 Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState stateService play Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::syncState currentStatus play Jun 16 13:59:44 servicedisco volumio[964]: info: Received an update from plugin. extracting info from payload Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreStateMachine::pushState Jun 16 13:59:44 servicedisco volumio[964]: info: CoreCommandRouter::volumioPushState Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 93ms Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 91ms Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 90ms Jun 16 13:59:44 servicedisco volumio[964]: info: ------------------------------ 88ms Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: info: FusionDsp - Volumio is playing Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:44 servicedisco volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 13:59:46 servicedisco volumio[964]: Searching plugin music_service/webradio Jun 16 13:59:46 servicedisco volumio[964]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 16 13:59:47 servicedisco volumio[964]: Use XMLElement.setAttribute instead Jun 16 13:59:47 servicedisco volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 13:59:47 servicedisco volumio[964]: TypeError: children[i].attr(...).value is not a function Jun 16 13:59:47 servicedisco volumio[964]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 16 13:59:47 servicedisco volumio[964]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 16 13:59:47 servicedisco volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 13:59:47 servicedisco sudo[67056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-16 13:58' Jun 16 13:59:47 servicedisco sudo[67056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 13:59:47 servicedisco sudo[67056]: pam_unix(sudo:session): session closed for user root Jun 16 13:59:48 servicedisco volumio-remote-updater[668]: [2025-06-16 13:59:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 16 13:59:48 servicedisco volumio-remote-updater[668]: [2025-06-16 13:59:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 16 13:59:48 servicedisco go-librespot[1450]: time="2025-06-16T13:59:48+03:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 16 13:59:48 servicedisco systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 16 13:59:49 servicedisco systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 16 13:59:49 servicedisco systemd[1]: volumio.service: Consumed 24min 22.767s CPU time. Jun 16 13:59:49 servicedisco systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 16 13:59:49 servicedisco systemd[1]: dynamicswap.service: Deactivated successfully. Jun 16 13:59:49 servicedisco systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 16 13:59:49 servicedisco systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 16 13:59:49 servicedisco systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 16 13:59:49 servicedisco systemd[1]: volumio.service: Consumed 24min 22.767s CPU time. Jun 16 13:59:49 servicedisco systemd[1]: Started volumio.service - Volumio Backend Module. Jun 16 13:59:49 servicedisco systemd[1]: dynamicswap.service: Deactivated successfully. Jun 16 13:59:51 servicedisco volumio[67088]: info: ------------------------------------------- Jun 16 13:59:51 servicedisco volumio[67088]: info: ----- Volumio3 ---- Jun 16 13:59:51 servicedisco volumio[67088]: info: ------------------------------------------- Jun 16 13:59:51 servicedisco volumio[67088]: info: ----- System startup ---- Jun 16 13:59:51 servicedisco volumio[67088]: info: ------------------------------------------- Jun 16 13:59:51 servicedisco volumio[67088]: info: MYVOLUMIO Environment detected Jun 16 13:59:51 servicedisco volumio[67088]: info: Plugin folders cleanup Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning into folder /volumio/app/plugins/ Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category audio_interface Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category miscellanea Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category music_service Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category plugins.json Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category system_controller Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category user_interface Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning into folder /data/plugins/ Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category audio_interface Jun 16 13:59:51 servicedisco volumio[67088]: info: Scanning category music_service Jun 16 13:59:51 servicedisco volumio[67088]: info: Plugin folders cleanup completed Jun 16 13:59:51 servicedisco volumio[67088]: info: ------------------------------------------- Jun 16 13:59:51 servicedisco volumio[67088]: info: ----- Core plugins startup ---- Jun 16 13:59:51 servicedisco volumio[67088]: info: ------------------------------------------- Jun 16 13:59:51 servicedisco volumio[67088]: info: Loading plugins from folder /volumio/app/plugins/ Jun 16 13:59:51 servicedisco volumio[67088]: info: Adding plugin upnp to MyMusic Plugins Jun 16 13:59:52 servicedisco volumio[67088]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 16 13:59:52 servicedisco volumio[67088]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 16 13:59:52 servicedisco volumio[67088]: info: Loading plugins from folder /data/plugins/ Jun 16 13:59:52 servicedisco volumio[67088]: info: Loading plugin "system"... Jun 16 13:59:52 servicedisco volumio[67088]: info: Loading plugin "appearance"... Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "network"... Jun 16 13:59:53 servicedisco volumio[67088]: info: Refreshing Cached IP Addresses Jun 16 13:59:53 servicedisco sudo[67121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 13:59:53 servicedisco sudo[67121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 13:59:53 servicedisco sudo[67121]: pam_unix(sudo:session): session closed for user root Jun 16 13:59:53 servicedisco sudo[67123]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 13:59:53 servicedisco sudo[67123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "services"... Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "alsa_controller"... Jun 16 13:59:53 servicedisco sudo[67123]: pam_unix(sudo:session): session closed for user root Jun 16 13:59:53 servicedisco sudo[67131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 16 13:59:53 servicedisco sudo[67131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 13:59:53 servicedisco sudo[67131]: pam_unix(sudo:session): session closed for user root Jun 16 13:59:53 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "wizard"... Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "networkfs"... Jun 16 13:59:53 servicedisco volumio[67088]: info: Starting Udev Watcher for removable devices Jun 16 13:59:53 servicedisco volumio[67088]: info: Ignoring mount for partition: boot Jun 16 13:59:53 servicedisco volumio[67088]: info: Ignoring mount for partition: volumio Jun 16 13:59:53 servicedisco volumio[67088]: info: Ignoring mount for partition: volumio_data Jun 16 13:59:53 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "volumio_command_line_client"... Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "upnp"... Jun 16 13:59:53 servicedisco volumio[67088]: info: [1750071593361] Starting Upmpd Daemon Jun 16 13:59:53 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "my_music"... Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "mpd"... Jun 16 13:59:53 servicedisco volumio-remote-updater[668]: [2025-06-16 13:59:53] [connect] Successful connection Jun 16 13:59:53 servicedisco volumio[67088]: info: Loading plugin "upnp_browser"... Jun 16 13:59:53 servicedisco go-librespot[1450]: time="2025-06-16T13:59:53+03:00" level=trace msg="sent dealer ping" Jun 16 13:59:53 servicedisco go-librespot[1450]: time="2025-06-16T13:59:53+03:00" level=trace msg="received dealer pong" Jun 16 13:59:55 servicedisco volumio[67088]: info: Loading plugin "alarm-clock"... Jun 16 13:59:55 servicedisco volumio[67088]: info: Loading plugin "airplay_emulation"... Jun 16 13:59:55 servicedisco volumio[67088]: info: Starting Shairport Sync Jun 16 13:59:55 servicedisco volumio[67088]: info: Loading plugin "last_100"... Jun 16 13:59:55 servicedisco volumio[67088]: info: Loading plugin "webradio"... Jun 16 13:59:55 servicedisco volumio[67088]: info: Loading plugin "i2s_dacs"... Jun 16 13:59:55 servicedisco volumio[67088]: info: I2S DAC not set, start Auto-detection Jun 16 13:59:55 servicedisco volumio[67088]: info: Loading plugin "volumiodiscovery"... Jun 16 13:59:55 servicedisco volumio[67088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 16 13:59:55 servicedisco volumio[67088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 13:59:55 servicedisco volumio[67088]: *** WARNING *** For more information see Jun 16 13:59:55 servicedisco volumio[67088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 16 13:59:55 servicedisco volumio[67088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 13:59:55 servicedisco volumio[67088]: *** WARNING *** For more information see Jun 16 13:59:55 servicedisco node[67088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 16 13:59:55 servicedisco node[67088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 13:59:55 servicedisco node[67088]: *** WARNING *** For more information see Jun 16 13:59:55 servicedisco node[67088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 16 13:59:55 servicedisco node[67088]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 13:59:55 servicedisco node[67088]: *** WARNING *** For more information see Jun 16 13:59:55 servicedisco volumio[67088]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 16 13:59:55 servicedisco volumio[67088]: info: Discovery: Started advertising with name: ServiceDisco Jun 16 13:59:55 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 16 13:59:55 servicedisco volumio[67088]: info: Loading plugin "spop"... Jun 16 13:59:56 servicedisco volumio[67088]: info: Loading plugin "youtube2"... Jun 16 13:59:59 servicedisco volumio[67088]: info: Loading plugin "ytcr"... Jun 16 14:00:03 servicedisco volumio[67088]: info: Loading plugin "ytmusic"... Jun 16 14:00:04 servicedisco systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 16 14:00:05 servicedisco volumio[67088]: info: Loading plugin "outputs"... Jun 16 14:00:05 servicedisco volumio[67088]: info: Loading plugin "albumart"... Jun 16 14:00:05 servicedisco volumio[67088]: info: Plugin example_plugin is not enabled Jun 16 14:00:05 servicedisco volumio[67088]: info: Loading plugin "inputs"... Jun 16 14:00:05 servicedisco volumio[67088]: info: Loading plugin "updater_comm"... Jun 16 14:00:05 servicedisco volumio[67088]: info: Plugin mpdemulation is not enabled Jun 16 14:00:05 servicedisco volumio[67088]: info: Loading plugin "rest_api"... Jun 16 14:00:05 servicedisco volumio[67088]: info: Loading plugin "websocket"... Jun 16 14:00:05 servicedisco volumio[67088]: info: Starting Socket.io Server version 1.7.4 Jun 16 14:00:05 servicedisco volumio[67088]: info: Loading plugin "fusiondsp"... Jun 16 14:00:05 servicedisco volumio[67189]: Forking 3 albumart workers Jun 16 14:00:06 servicedisco volumio-remote-updater[668]: [2025-06-16 14:00:06] [connect] Successful connection Jun 16 14:00:06 servicedisco volumio[67088]: info: Applying required configuration parameters for plugin fusiondsp Jun 16 14:00:06 servicedisco volumio[67088]: info: Loading i18n strings for locale en Jun 16 14:00:06 servicedisco volumio[67088]: Updating browse sources language Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::initPlayerControls Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 16 14:00:06 servicedisco volumio[67088]: Express server listening on port 3000 Jun 16 14:00:06 servicedisco volumio[67088]: [Metrics] WebUI: 16s 165.33ms Jun 16 14:00:06 servicedisco volumio[67088]: info: Setting Device type: x86 Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreStateMachine::resetVolumioState Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreStateMachine::getcurrentVolume Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::volumioRetrievevolume Jun 16 14:00:06 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:06 servicedisco sudo[67233]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 14:00:06 servicedisco sudo[67233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:06 servicedisco volumio[67088]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 16 14:00:06 servicedisco volumio[67088]: info: Completed loading Core Plugins Jun 16 14:00:06 servicedisco volumio[67088]: info: Preparing to generate the ALSA configuration file Jun 16 14:00:07 servicedisco sudo[67233]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:07 servicedisco sudo[67235]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 14:00:07 servicedisco sudo[67235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:07 servicedisco volumio[67088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 16 14:00:07 servicedisco volumio[67088]: info: Reading ALSA contributions from plugins. Jun 16 14:00:07 servicedisco sudo[67235]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:07 servicedisco volumio[67088]: info: Volumio Network Manager: Network status updated: 1 Jun 16 14:00:07 servicedisco volumio[67201]: Starting albumart workers Jun 16 14:00:07 servicedisco volumio[67202]: Starting albumart workers Jun 16 14:00:07 servicedisco volumio[67200]: Starting albumart workers Jun 16 14:00:07 servicedisco volumio[67088]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 16 14:00:07 servicedisco volumio[67088]: wlan0 Interface doesn't support scanning. Jun 16 14:00:07 servicedisco volumio[67088]: info: Cannot use regular scanning, forcing with ap-force Jun 16 14:00:07 servicedisco sudo[67243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 16 14:00:07 servicedisco sudo[67243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:07 servicedisco sudo[67243]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:07 servicedisco volumio[67088]: command failed: No such device (-19) Jun 16 14:00:07 servicedisco volumio[67088]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 16 14:00:07 servicedisco volumio[67088]: command failed: No such device (-19) Jun 16 14:00:07 servicedisco volumio[67088]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 16 14:00:07 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 16 14:00:07 servicedisco volumio[67088]: info: VolumeController:: Volume=25 Mute =false Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreStateMachine::pushState Jun 16 14:00:07 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreStateMachine::updateTrackBlock Jun 16 14:00:07 servicedisco volumio[67088]: info: CorePlayQueue::getTrackBlock Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreCommandRouter::volumioRetrievevolume Jun 16 14:00:07 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.1.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 16 14:00:07 servicedisco volumio[67088]: info: Reloading queue from file Jun 16 14:00:07 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreStateMachine::setRepeat false single undefined Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreStateMachine::pushState Jun 16 14:00:07 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreStateMachine::setRandom null Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreStateMachine::pushState Jun 16 14:00:07 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:07 servicedisco volumio[67088]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:07 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.1.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Jun 16 14:00:08 servicedisco volumio[67088]: info: VolumeController:: Volume=25 Mute =false Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreStateMachine::pushState Jun 16 14:00:08 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:08 servicedisco volumio[67088]: info: Discovery: adding 0f657bce-985f-4435-8ed0-69e4a3ac7996 Jun 16 14:00:08 servicedisco volumio[67088]: info: Discovery: Found device VolumioBuilding Jun 16 14:00:08 servicedisco sudo[67270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 16 14:00:08 servicedisco sudo[67270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:08 servicedisco volumio[67088]: info: Discovery: Connecting to remote: 10.10.1.111 Jun 16 14:00:08 servicedisco sudo[67270]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:08 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 16 14:00:08 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43:3000 from 10.10.1.111 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 16 14:00:08 servicedisco volumio[67088]: info: Upmpdcli Daemon Started Jun 16 14:00:08 servicedisco volumio[67088]: info: Asound.conf file unchanged, so no further update is needed Jun 16 14:00:08 servicedisco volumio[67088]: info: Output device has changed, restarting MPD Jun 16 14:00:08 servicedisco volumio[67088]: info: Output device has changed, restarting Shairport Sync Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:08 servicedisco sudo[67280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 16 14:00:08 servicedisco sudo[67280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:08 servicedisco sudo[67280]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:08 servicedisco sudo[67282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 16 14:00:08 servicedisco sudo[67282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:08 servicedisco volumio[67088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:08 servicedisco volumio[67088]: info: ___________ START PLUGINS ___________ Jun 16 14:00:08 servicedisco volumio[67088]: info: ControllerMpd::onStart: Initializing MPD Jun 16 14:00:08 servicedisco volumio[67088]: info: Creating MPD Configuration file Jun 16 14:00:08 servicedisco systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:08 servicedisco volumio[67088]: info: [1750071608400] CoreMusicLibrary::Adding element Media Servers Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:08 servicedisco sudo[67290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 16 14:00:08 servicedisco sudo[67290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:08 servicedisco sudo[67290]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:08 servicedisco sudo[67292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 16 14:00:08 servicedisco sudo[67292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:08 servicedisco volumio[67088]: info: [1750071608512] CoreMusicLibrary::Adding element Last_100 Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:08 servicedisco volumio[67088]: info: [1750071608518] CoreMusicLibrary::Adding element Webradio Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 14:00:08 servicedisco volumio[67088]: info: Initializing BBC Radios Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: Creating Spotify config file Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:08 servicedisco volumio[67088]: info: [1750071608625] CoreMusicLibrary::Adding element YouTube2 Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:08 servicedisco volumio[67088]: Cannot find translation for source YouTube2 Jun 16 14:00:08 servicedisco volumio[67088]: info: [ytcr] Data store TTL expired - clearing it... Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:08 servicedisco volumio[67088]: info: [1750071608693] CoreMusicLibrary::Adding element YouTube Music Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:08 servicedisco volumio[67088]: Cannot find translation for source YouTube2 Jun 16 14:00:08 servicedisco volumio[67088]: Cannot find translation for source YouTube Music Jun 16 14:00:08 servicedisco volumio[67088]: info: Loading i18n strings for locale en Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 16 14:00:08 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:08 servicedisco volumio[67088]: info: FusionDsp - mixtype--------------------- Hardware Jun 16 14:00:08 servicedisco volumio[67088]: info: Volumio Calling Home Jun 16 14:00:08 servicedisco volumio[67088]: info: Preparing to generate the ALSA configuration file Jun 16 14:00:08 servicedisco systemd[1]: mpd.service: Deactivated successfully. Jun 16 14:00:08 servicedisco systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 16 14:00:08 servicedisco systemd[1]: mpd.service: Consumed 5min 26.734s CPU time. Jun 16 14:00:08 servicedisco systemd[1]: mpd.socket: Deactivated successfully. Jun 16 14:00:08 servicedisco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 16 14:00:08 servicedisco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 16 14:00:08 servicedisco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 16 14:00:08 servicedisco systemd[1]: Starting mpd.service - Music Player Daemon... Jun 16 14:00:09 servicedisco sudo[67321]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 16 14:00:09 servicedisco sudo[67321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 14:00:09 servicedisco sudo[67321]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:09 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.1.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 16 14:00:09 servicedisco volumio[67088]: info: Discovery: Connected to remote: 10.10.1.111 Jun 16 14:00:09 servicedisco volumio[67088]: info: Discovery: adding c029b43a-e024-45a8-ad48-8c9d6c316176 Jun 16 14:00:09 servicedisco volumio[67088]: info: Discovery: Found device ServiceDisco Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:09 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:09 servicedisco volumio[67088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 16 14:00:09 servicedisco volumio[67088]: info: Reading ALSA contributions from plugins. Jun 16 14:00:09 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 16 14:00:09 servicedisco volumio[67088]: info: MPD Permissions set Jun 16 14:00:09 servicedisco volumio[67088]: info: MPD Permissions set Jun 16 14:00:09 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43:3000 from 10.10.1.111 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 16 14:00:09 servicedisco volumio[67088]: info: Volumio called home Jun 16 14:00:09 servicedisco volumio[67088]: info: Spotify config file written Jun 16 14:00:09 servicedisco volumio[67088]: info: Discovery: this is already registered, c029b43a-e024-45a8-ad48-8c9d6c316176 Jun 16 14:00:09 servicedisco volumio[67088]: info: Discovery: Found device ServiceDisco Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:09 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:09 servicedisco sudo[67325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 16 14:00:09 servicedisco sudo[67325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:09 servicedisco volumio-remote-updater[668]: [2025-06-16 14:00:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750071606 101 Jun 16 14:00:09 servicedisco volumio[67088]: 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 Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 16 14:00:09 servicedisco systemd[1]: go-librespot-daemon.service: Killing process 10259 (go-librespot) with signal SIGKILL. Jun 16 14:00:09 servicedisco systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 16 14:00:09 servicedisco systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 14:00:09 servicedisco systemd[1]: go-librespot-daemon.service: Consumed 8min 32.141s CPU time. Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 14:00:09 servicedisco go-librespot[67333]: go-librespot daemon starting... Jun 16 14:00:09 servicedisco sudo[67325]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 16 14:00:09 servicedisco go-librespot[67338]: time="2025-06-16T14:00:09+03:00" level=info msg="running go-librespot 0.2.0" Jun 16 14:00:09 servicedisco go-librespot[67338]: time="2025-06-16T14:00:09+03:00" level=debug msg="app state loaded" Jun 16 14:00:09 servicedisco go-librespot[67338]: time="2025-06-16T14:00:09+03:00" level=debug msg="stored credentials not found" Jun 16 14:00:09 servicedisco go-librespot[67338]: time="2025-06-16T14:00:09+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 14:00:09 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:09 servicedisco volumio[67088]: info: No need to fix Spotify hosts Jun 16 14:00:10 servicedisco go-librespot[67338]: time="2025-06-16T14:00:10+03: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]" Jun 16 14:00:10 servicedisco go-librespot[67338]: time="2025-06-16T14:00:10+03: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]" Jun 16 14:00:10 servicedisco go-librespot[67338]: time="2025-06-16T14:00:10+03: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]" Jun 16 14:00:10 servicedisco go-librespot[67338]: time="2025-06-16T14:00:10+03:00" level=info msg="zeroconf server listening on port 43453" Jun 16 14:00:10 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 16 14:00:10 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43:3000 from 10.10.1.111 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jun 16 14:00:10 servicedisco volumio[67088]: info: Starting Shairport Sync Jun 16 14:00:10 servicedisco volumio[67088]: info: Starting Shairport Sync Jun 16 14:00:10 servicedisco volumio[67088]: info: Starting Shairport Sync Jun 16 14:00:10 servicedisco sudo[67353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:10 servicedisco sudo[67353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:10 servicedisco volumio[67088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 16 14:00:10 servicedisco sudo[67355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:10 servicedisco sudo[67357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:10 servicedisco sudo[67355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:10 servicedisco sudo[67357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:10 servicedisco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 16 14:00:10 servicedisco volumio[67088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 16 14:00:10 servicedisco systemd[1]: shairport-sync.service: Deactivated successfully. Jun 16 14:00:10 servicedisco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:10 servicedisco systemd[1]: shairport-sync.service: Consumed 1min 45.313s CPU time. Jun 16 14:00:10 servicedisco volumio[67088]: info: Asound.conf file unchanged, so no further update is needed Jun 16 14:00:10 servicedisco volumio[67088]: info: Output device has changed, restarting MPD Jun 16 14:00:10 servicedisco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:10 servicedisco volumio[67088]: info: Output device has changed, restarting Shairport Sync Jun 16 14:00:10 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:10 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:10 servicedisco sudo[67353]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:10 servicedisco sudo[67363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 16 14:00:10 servicedisco sudo[67363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:10 servicedisco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 16 14:00:10 servicedisco systemd[1]: shairport-sync.service: Deactivated successfully. Jun 16 14:00:10 servicedisco sudo[67363]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:10 servicedisco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:10 servicedisco sudo[67365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 16 14:00:10 servicedisco sudo[67365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:10 servicedisco volumio[67088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:10 servicedisco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:10 servicedisco sudo[67357]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:10 servicedisco sudo[67355]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:10 servicedisco systemd[1]: mpd.service: Deactivated successfully. Jun 16 14:00:10 servicedisco systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 16 14:00:10 servicedisco systemd[1]: mpd.service: Consumed 1.718s CPU time. Jun 16 14:00:10 servicedisco systemd[1]: mpd.socket: Deactivated successfully. Jun 16 14:00:10 servicedisco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 16 14:00:10 servicedisco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 16 14:00:10 servicedisco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 16 14:00:10 servicedisco systemd[1]: Starting mpd.service - Music Player Daemon... Jun 16 14:00:10 servicedisco volumio[67088]: error: Upnp client error: Error: read ECONNRESET Jun 16 14:00:10 servicedisco volumio[67088]: error: Upnp client error: Error: read ECONNRESET Jun 16 14:00:10 servicedisco volumio[67088]: info: MPD Permissions set Jun 16 14:00:10 servicedisco volumio[67088]: info: Shairport-Sync Started Jun 16 14:00:10 servicedisco volumio[67088]: Error adding Membership: Error: addMembership EINVAL Jun 16 14:00:10 servicedisco volumio[67088]: info: Shairport-Sync Started Jun 16 14:00:10 servicedisco volumio[67088]: info: Shairport-Sync Started Jun 16 14:00:10 servicedisco volumio[67088]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 16 14:00:10 servicedisco volumio[67088]: 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 Jun 16 14:00:10 servicedisco volumio[67088]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 16 14:00:10 servicedisco volumio[67088]: info: camilladsp service started and running in background, instance 1 Jun 16 14:00:10 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:10 servicedisco volumio[67088]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 <-AAAAAAAAAAAAA Jun 16 14:00:10 servicedisco volumio[67088]: info: FusionDsp loaded Jun 16 14:00:10 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 14:00:10 servicedisco volumio[67088]: error: FusionDsp - Socket connection not established Jun 16 14:00:10 servicedisco volumio[67088]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 16 14:00:10 servicedisco volumio[67088]: info: Adding Signal Path Element [object Object] Jun 16 14:00:10 servicedisco volumio[67088]: info: Adding fusiondspeq DSP Signal Path Element Jun 16 14:00:10 servicedisco volumio[67088]: info: FusionDsp - ---- installed callbackRead Jun 16 14:00:10 servicedisco volumio[67088]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 16 14:00:10 servicedisco sudo[67389]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 16 14:00:10 servicedisco sudo[67389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 14:00:11 servicedisco sudo[67389]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:11 servicedisco sudo[67408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 16 14:00:11 servicedisco sudo[67408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:11 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:11 servicedisco sudo[67408]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:11 servicedisco volumio[67088]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:11 servicedisco sudo[67421]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 14:00:11 servicedisco sudo[67421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:11 servicedisco sudo[67423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 14:00:11 servicedisco sudo[67423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:11 servicedisco sudo[67421]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:11 servicedisco volumio[67088]: info: Starting Shairport Sync Jun 16 14:00:11 servicedisco sudo[67423]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:11 servicedisco sudo[67430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:11 servicedisco sudo[67430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:11 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:11 servicedisco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 16 14:00:11 servicedisco systemd[1]: shairport-sync.service: Deactivated successfully. Jun 16 14:00:11 servicedisco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:11 servicedisco volumio[67088]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jun 16 14:00:11 servicedisco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:11 servicedisco sudo[67430]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:11 servicedisco volumio[67088]: info: Shairport-Sync Started Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetVisibleSources Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:11 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetQueue Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreStateMachine::getQueue Jun 16 14:00:11 servicedisco volumio[67088]: info: CorePlayQueue::getQueue Jun 16 14:00:11 servicedisco volumio[67088]: info: Listing playlists Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 16 14:00:11 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 16 14:00:12 servicedisco volumio[67088]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 16 14:00:12 servicedisco volumio[67088]: info: CoreCommandRouter::volumioRetrievevolume Jun 16 14:00:13 servicedisco volumio[67088]: info: go-librespot daemon successfully initialized Jun 16 14:00:13 servicedisco volumio[67088]: info: VolumeController:: Volume=25 Mute =false Jun 16 14:00:13 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:13 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:13 servicedisco volumio[67088]: info: CoreStateMachine::pushState Jun 16 14:00:13 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:13 servicedisco volumio[67088]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:14 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 14:00:14 servicedisco volumio[67088]: info: Received Get System Info Jun 16 14:00:14 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 14:00:14 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 14:00:14 servicedisco volumio[67088]: info: Discovery: Getting this device information Jun 16 14:00:14 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:14 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:14 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 14:00:14 servicedisco mpd[67410]: 2025-06-16T14:00:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 16 14:00:14 servicedisco systemd[1]: Started mpd.service - Music Player Daemon. Jun 16 14:00:14 servicedisco sudo[67292]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:14 servicedisco sudo[67365]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:14 servicedisco sudo[67282]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:14 servicedisco volumio[67088]: error: MPD error: The expression evaluated to a falsy value: Jun 16 14:00:14 servicedisco volumio[67088]: assert.ok(self.idling) Jun 16 14:00:14 servicedisco volumio[67088]: error: The expression evaluated to a falsy value: Jun 16 14:00:14 servicedisco volumio[67088]: assert.ok(self.idling) Jun 16 14:00:14 servicedisco volumio[67088]: error: MPD error: The expression evaluated to a falsy value: Jun 16 14:00:14 servicedisco volumio[67088]: assert.ok(self.idling) Jun 16 14:00:14 servicedisco volumio[67088]: error: The expression evaluated to a falsy value: Jun 16 14:00:14 servicedisco volumio[67088]: assert.ok(self.idling) Jun 16 14:00:14 servicedisco volumio[67088]: info: MPD running with PID67410 Jun 16 14:00:14 servicedisco volumio[67088]: ,establishing connection Jun 16 14:00:14 servicedisco volumio[67088]: error: MPD error: The expression evaluated to a falsy value: Jun 16 14:00:14 servicedisco volumio[67088]: assert.ok(self.idling) Jun 16 14:00:14 servicedisco volumio[67088]: error: The expression evaluated to a falsy value: Jun 16 14:00:14 servicedisco volumio[67088]: assert.ok(self.idling) Jun 16 14:00:14 servicedisco volumio[67088]: info: Completed starting Core Plugins Jun 16 14:00:14 servicedisco volumio[67088]: info: ------------------------------------------- Jun 16 14:00:14 servicedisco volumio[67088]: info: ----- MyVolumio plugins startup ---- Jun 16 14:00:14 servicedisco volumio[67088]: info: ------------------------------------------- Jun 16 14:00:14 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 16 14:00:14 servicedisco volumio[67088]: error: updateQueue error: null Jun 16 14:00:14 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 16 14:00:15 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 16 14:00:15 servicedisco volumio[67088]: info: Preload queue cleared Jun 16 14:00:16 servicedisco volumio[67088]: info: Initializing connection to go-librespot Websocket Jun 16 14:00:16 servicedisco go-librespot[67338]: time="2025-06-16T14:00:16+03:00" level=debug msg="new websocket client" Jun 16 14:00:16 servicedisco volumio[67088]: info: Connection to go-librespot Websocket established Jun 16 14:00:16 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 16 14:00:16 servicedisco volumio[67088]: info: TuneIn handleBrowseUri: tunein/local Jun 16 14:00:17 servicedisco volumio[67088]: info: Preload queue cleared Jun 16 14:00:19 servicedisco volumio[67088]: info: Getting Spotify volume Jun 16 14:00:19 servicedisco volumio[67088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 16 14:00:19 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:19 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:19 servicedisco volumio[67088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 16 14:00:19 servicedisco volumio[67088]: SPOTIFY: SPOTIFY VOLUME undefined Jun 16 14:00:19 servicedisco volumio[67088]: SPOTIFY: VOLUMIO VOLUME 25 Jun 16 14:00:19 servicedisco volumio[67088]: info: Aligning Spotify Volume to Volumio Volume Jun 16 14:00:19 servicedisco volumio[67088]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:19 servicedisco volumio[67088]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:19 servicedisco volumio[67088]: info: Setting Spotify Volume from Volumio: 25 Jun 16 14:00:19 servicedisco volumio[67088]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 16 14:00:20 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 16 14:00:20 servicedisco volumio[67088]: info: Preload queue cleared Jun 16 14:00:20 servicedisco volumio[67088]: SPOTIFY: SETTING SPOTIFY VOLUME 25 Jun 16 14:00:20 servicedisco volumio[67088]: info: Sending Spotify command with payload to local API: /player/volume Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 16 14:00:23 servicedisco volumio[67088]: info: Adding plugin bluetooth to MyMusic Plugins Jun 16 14:00:23 servicedisco volumio[67088]: info: Adding plugin multiroom to MyMusic Plugins Jun 16 14:00:23 servicedisco volumio[67088]: info: Adding plugin metavolumio to MyMusic Plugins Jun 16 14:00:23 servicedisco volumio[67088]: info: Adding plugin cd_controller to MyMusic Plugins Jun 16 14:00:23 servicedisco volumio[67088]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 16 14:00:23 servicedisco volumio[67088]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 16 14:00:23 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 16 14:00:24 servicedisco volumio[67088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 16 14:00:24 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 16 14:00:24 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:24 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:24 servicedisco volumio[67088]: info: Starting MyVolumio Remote Streaming Endpoints Jun 16 14:00:24 servicedisco volumio[67088]: info: MyVolumio login type: Token Jun 16 14:00:24 servicedisco volumio[67088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 16 14:00:24 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 16 14:00:25 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 16 14:00:25 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 16 14:00:25 servicedisco volumio[67088]: info: Streaming services startup Jun 16 14:00:25 servicedisco volumio[67088]: info: Starting Streaming Daemon Jun 16 14:00:25 servicedisco sudo[67501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 16 14:00:25 servicedisco sudo[67501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:25 servicedisco volumio[67088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 16 14:00:25 servicedisco sudo[67501]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:25 servicedisco volumio[67088]: Searching plugin music_service/webradio Jun 16 14:00:25 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 16 14:00:25 servicedisco volumio[67088]: Searching plugin music_service/webradio Jun 16 14:00:25 servicedisco volumio[67088]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 16 14:00:25 servicedisco volumio[67088]: error: Cannot start Volumio Streaming Daemon Jun 16 14:00:25 servicedisco volumio[67088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 16 14:00:25 servicedisco volumio[67088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 16 14:00:25 servicedisco volumio[67088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 14:00:25 servicedisco volumio[67088]: TypeError: Cannot read properties of undefined (reading 'length') Jun 16 14:00:25 servicedisco volumio[67088]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jun 16 14:00:25 servicedisco volumio[67088]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 16 14:00:25 servicedisco volumio[67088]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jun 16 14:00:25 servicedisco volumio[67088]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 16 14:00:25 servicedisco volumio[67088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 14:00:26 servicedisco sudo[67522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-16 13:59' Jun 16 14:00:26 servicedisco sudo[67522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:26 servicedisco sudo[67522]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:27 servicedisco go-librespot[67338]: time="2025-06-16T14:00:27+03:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 16 14:00:27 servicedisco volumio-remote-updater[668]: [2025-06-16 14:00:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 16 14:00:27 servicedisco volumio-remote-updater[668]: [2025-06-16 14:00:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 16 14:00:27 servicedisco systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 16 14:00:27 servicedisco systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 16 14:00:27 servicedisco systemd[1]: volumio.service: Consumed 44.091s CPU time. Jun 16 14:00:27 servicedisco systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 16 14:00:27 servicedisco systemd[1]: dynamicswap.service: Deactivated successfully. Jun 16 14:00:27 servicedisco systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jun 16 14:00:27 servicedisco systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 16 14:00:27 servicedisco systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 16 14:00:27 servicedisco systemd[1]: volumio.service: Consumed 44.091s CPU time. Jun 16 14:00:27 servicedisco systemd[1]: Started volumio.service - Volumio Backend Module. Jun 16 14:00:27 servicedisco systemd[1]: dynamicswap.service: Deactivated successfully. Jun 16 14:00:29 servicedisco volumio[67544]: info: ------------------------------------------- Jun 16 14:00:29 servicedisco volumio[67544]: info: ----- Volumio3 ---- Jun 16 14:00:29 servicedisco volumio[67544]: info: ------------------------------------------- Jun 16 14:00:29 servicedisco volumio[67544]: info: ----- System startup ---- Jun 16 14:00:29 servicedisco volumio[67544]: info: ------------------------------------------- Jun 16 14:00:29 servicedisco volumio[67544]: info: MYVOLUMIO Environment detected Jun 16 14:00:29 servicedisco volumio[67544]: info: Plugin folders cleanup Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning into folder /volumio/app/plugins/ Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category audio_interface Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category miscellanea Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category music_service Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category plugins.json Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category system_controller Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category user_interface Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning into folder /data/plugins/ Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category audio_interface Jun 16 14:00:29 servicedisco volumio[67544]: info: Scanning category music_service Jun 16 14:00:29 servicedisco volumio[67544]: info: Plugin folders cleanup completed Jun 16 14:00:29 servicedisco volumio[67544]: info: ------------------------------------------- Jun 16 14:00:29 servicedisco volumio[67544]: info: ----- Core plugins startup ---- Jun 16 14:00:29 servicedisco volumio[67544]: info: ------------------------------------------- Jun 16 14:00:29 servicedisco volumio[67544]: info: Loading plugins from folder /volumio/app/plugins/ Jun 16 14:00:29 servicedisco volumio[67544]: info: Adding plugin upnp to MyMusic Plugins Jun 16 14:00:29 servicedisco volumio[67544]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 16 14:00:29 servicedisco volumio[67544]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 16 14:00:29 servicedisco volumio[67544]: info: Loading plugins from folder /data/plugins/ Jun 16 14:00:30 servicedisco volumio[67544]: info: Loading plugin "system"... Jun 16 14:00:30 servicedisco volumio[67544]: info: Loading plugin "appearance"... Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "network"... Jun 16 14:00:31 servicedisco volumio[67544]: info: Refreshing Cached IP Addresses Jun 16 14:00:31 servicedisco sudo[67576]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 14:00:31 servicedisco sudo[67576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "services"... Jun 16 14:00:31 servicedisco sudo[67578]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 14:00:31 servicedisco sudo[67578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "alsa_controller"... Jun 16 14:00:31 servicedisco sudo[67576]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:31 servicedisco sudo[67585]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 16 14:00:31 servicedisco sudo[67585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:31 servicedisco sudo[67578]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:31 servicedisco sudo[67585]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:31 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "wizard"... Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "networkfs"... Jun 16 14:00:31 servicedisco volumio[67544]: info: Starting Udev Watcher for removable devices Jun 16 14:00:31 servicedisco volumio[67544]: info: Ignoring mount for partition: boot Jun 16 14:00:31 servicedisco volumio[67544]: info: Ignoring mount for partition: volumio Jun 16 14:00:31 servicedisco volumio[67544]: info: Ignoring mount for partition: volumio_data Jun 16 14:00:31 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "volumio_command_line_client"... Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "upnp"... Jun 16 14:00:31 servicedisco volumio[67544]: info: [1750071631315] Starting Upmpd Daemon Jun 16 14:00:31 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "my_music"... Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "mpd"... Jun 16 14:00:31 servicedisco volumio[67544]: info: Loading plugin "upnp_browser"... Jun 16 14:00:32 servicedisco volumio-remote-updater[668]: [2025-06-16 14:00:32] [connect] Successful connection Jun 16 14:00:33 servicedisco volumio[67544]: info: Loading plugin "alarm-clock"... Jun 16 14:00:33 servicedisco volumio[67544]: info: Loading plugin "airplay_emulation"... Jun 16 14:00:33 servicedisco volumio[67544]: info: Starting Shairport Sync Jun 16 14:00:33 servicedisco volumio[67544]: info: Loading plugin "last_100"... Jun 16 14:00:33 servicedisco volumio[67544]: info: Loading plugin "webradio"... Jun 16 14:00:33 servicedisco volumio[67544]: info: Loading plugin "i2s_dacs"... Jun 16 14:00:33 servicedisco volumio[67544]: info: I2S DAC not set, start Auto-detection Jun 16 14:00:33 servicedisco volumio[67544]: info: Loading plugin "volumiodiscovery"... Jun 16 14:00:33 servicedisco volumio[67544]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 16 14:00:33 servicedisco volumio[67544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 14:00:33 servicedisco volumio[67544]: *** WARNING *** For more information see Jun 16 14:00:33 servicedisco volumio[67544]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 16 14:00:33 servicedisco volumio[67544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 14:00:33 servicedisco node[67544]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 16 14:00:33 servicedisco volumio[67544]: *** WARNING *** For more information see Jun 16 14:00:33 servicedisco node[67544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 14:00:33 servicedisco node[67544]: *** WARNING *** For more information see Jun 16 14:00:33 servicedisco node[67544]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 16 14:00:33 servicedisco node[67544]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 16 14:00:33 servicedisco node[67544]: *** WARNING *** For more information see Jun 16 14:00:33 servicedisco volumio[67544]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 16 14:00:33 servicedisco volumio[67544]: info: Discovery: Started advertising with name: ServiceDisco Jun 16 14:00:33 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 16 14:00:33 servicedisco volumio[67544]: info: Loading plugin "spop"... Jun 16 14:00:34 servicedisco volumio[67544]: info: Loading plugin "youtube2"... Jun 16 14:00:37 servicedisco volumio[67544]: info: Loading plugin "ytcr"... Jun 16 14:00:40 servicedisco volumio[67544]: info: Loading plugin "ytmusic"... Jun 16 14:00:43 servicedisco volumio[67544]: info: Loading plugin "outputs"... Jun 16 14:00:43 servicedisco volumio[67544]: info: Loading plugin "albumart"... Jun 16 14:00:43 servicedisco volumio[67544]: info: Plugin example_plugin is not enabled Jun 16 14:00:43 servicedisco volumio[67544]: info: Loading plugin "inputs"... Jun 16 14:00:43 servicedisco volumio[67544]: info: Loading plugin "updater_comm"... Jun 16 14:00:43 servicedisco volumio[67544]: info: Plugin mpdemulation is not enabled Jun 16 14:00:43 servicedisco volumio[67544]: info: Loading plugin "rest_api"... Jun 16 14:00:43 servicedisco volumio[67544]: info: Loading plugin "websocket"... Jun 16 14:00:43 servicedisco volumio[67544]: info: Starting Socket.io Server version 1.7.4 Jun 16 14:00:43 servicedisco volumio[67544]: info: Loading plugin "fusiondsp"... Jun 16 14:00:43 servicedisco volumio[67642]: Forking 3 albumart workers Jun 16 14:00:44 servicedisco volumio[67544]: info: Applying required configuration parameters for plugin fusiondsp Jun 16 14:00:44 servicedisco volumio[67544]: info: Loading i18n strings for locale en Jun 16 14:00:44 servicedisco volumio[67544]: Updating browse sources language Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::initPlayerControls Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 16 14:00:44 servicedisco volumio[67544]: Express server listening on port 3000 Jun 16 14:00:44 servicedisco volumio[67544]: [Metrics] WebUI: 15s 978.90ms Jun 16 14:00:44 servicedisco volumio[67544]: info: Setting Device type: x86 Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreStateMachine::resetVolumioState Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreStateMachine::getcurrentVolume Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::volumioRetrievevolume Jun 16 14:00:44 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:44 servicedisco sudo[67685]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 16 14:00:44 servicedisco sudo[67685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:44 servicedisco sudo[67685]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:44 servicedisco volumio[67544]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 16 14:00:44 servicedisco volumio[67544]: info: Completed loading Core Plugins Jun 16 14:00:44 servicedisco volumio-remote-updater[668]: [2025-06-16 14:00:44] [connect] Successful connection Jun 16 14:00:44 servicedisco volumio[67544]: info: Preparing to generate the ALSA configuration file Jun 16 14:00:44 servicedisco sudo[67687]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 16 14:00:44 servicedisco sudo[67687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:44 servicedisco volumio[67544]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 16 14:00:44 servicedisco volumio[67544]: info: Reading ALSA contributions from plugins. Jun 16 14:00:44 servicedisco sudo[67687]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:44 servicedisco volumio[67544]: info: Volumio Network Manager: Network status updated: 1 Jun 16 14:00:44 servicedisco volumio[67652]: Starting albumart workers Jun 16 14:00:44 servicedisco volumio[67653]: Starting albumart workers Jun 16 14:00:44 servicedisco volumio[67544]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 16 14:00:44 servicedisco volumio[67544]: wlan0 Interface doesn't support scanning. Jun 16 14:00:44 servicedisco volumio[67544]: info: Cannot use regular scanning, forcing with ap-force Jun 16 14:00:45 servicedisco volumio[67654]: Starting albumart workers Jun 16 14:00:45 servicedisco sudo[67696]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 16 14:00:45 servicedisco sudo[67696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:45 servicedisco sudo[67696]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:45 servicedisco volumio[67544]: command failed: No such device (-19) Jun 16 14:00:45 servicedisco volumio[67544]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 16 14:00:45 servicedisco volumio[67544]: command failed: No such device (-19) Jun 16 14:00:45 servicedisco volumio[67544]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 16 14:00:45 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 16 14:00:45 servicedisco volumio[67544]: info: VolumeController:: Volume=25 Mute =false Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreStateMachine::pushState Jun 16 14:00:45 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreStateMachine::updateTrackBlock Jun 16 14:00:45 servicedisco volumio[67544]: info: CorePlayQueue::getTrackBlock Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreCommandRouter::volumioRetrievevolume Jun 16 14:00:45 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 16 14:00:45 servicedisco volumio[67544]: info: Reloading queue from file Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreStateMachine::setRepeat false single undefined Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreStateMachine::pushState Jun 16 14:00:45 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreStateMachine::setRandom null Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreStateMachine::pushState Jun 16 14:00:45 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:45 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43:3000 from 10.10.1.111 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 16 14:00:45 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 16 14:00:45 servicedisco volumio[67544]: info: VolumeController:: Volume=25 Mute =false Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreStateMachine::pushState Jun 16 14:00:45 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:45 servicedisco volumio[67544]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:45 servicedisco volumio[67544]: info: Discovery: adding 0f657bce-985f-4435-8ed0-69e4a3ac7996 Jun 16 14:00:45 servicedisco volumio[67544]: info: Discovery: Found device VolumioBuilding Jun 16 14:00:45 servicedisco sudo[67722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 16 14:00:45 servicedisco sudo[67722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:45 servicedisco volumio[67544]: info: Discovery: Connecting to remote: 10.10.1.111 Jun 16 14:00:46 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43:3000 from 10.10.1.111 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 16 14:00:46 servicedisco systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 16 14:00:46 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 16 14:00:46 servicedisco volumio[67544]: info: Asound.conf file unchanged, so no further update is needed Jun 16 14:00:46 servicedisco volumio[67544]: info: Output device has changed, restarting MPD Jun 16 14:00:46 servicedisco sudo[67722]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:46 servicedisco dbus-daemon[656]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.449' (uid=0 pid=67733 comm="timedatectl show --property=NTPSynchronized --valu") Jun 16 14:00:46 servicedisco volumio[67544]: info: Output device has changed, restarting Shairport Sync Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:46 servicedisco sudo[67736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 16 14:00:46 servicedisco sudo[67736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:46 servicedisco sudo[67736]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:46 servicedisco sudo[67738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 16 14:00:46 servicedisco volumio[67544]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:46 servicedisco sudo[67738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:46 servicedisco volumio[67544]: info: ___________ START PLUGINS ___________ Jun 16 14:00:46 servicedisco volumio[67544]: info: ControllerMpd::onStart: Initializing MPD Jun 16 14:00:46 servicedisco volumio[67544]: info: Creating MPD Configuration file Jun 16 14:00:46 servicedisco systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:46 servicedisco systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 16 14:00:46 servicedisco volumio[67544]: info: [1750071646193] CoreMusicLibrary::Adding element Media Servers Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:46 servicedisco sudo[67747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 16 14:00:46 servicedisco sudo[67747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:46 servicedisco sudo[67749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 16 14:00:46 servicedisco sudo[67749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:46 servicedisco sudo[67747]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:46 servicedisco systemd[1]: mpd.service: Deactivated successfully. Jun 16 14:00:46 servicedisco systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 16 14:00:46 servicedisco systemd[1]: mpd.service: Consumed 3.588s CPU time. Jun 16 14:00:46 servicedisco systemd[1]: mpd.socket: Deactivated successfully. Jun 16 14:00:46 servicedisco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 16 14:00:46 servicedisco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:46 servicedisco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:46 servicedisco systemd[1]: Starting mpd.service - Music Player Daemon... Jun 16 14:00:46 servicedisco volumio[67544]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:46 servicedisco volumio[67544]: info: [1750071646352] CoreMusicLibrary::Adding element Last_100 Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:46 servicedisco volumio[67544]: info: [1750071646359] CoreMusicLibrary::Adding element Webradio Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:46 servicedisco systemd[1]: mpd.service: Deactivated successfully. Jun 16 14:00:46 servicedisco systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 14:00:46 servicedisco systemd[1]: mpd.socket: Deactivated successfully. Jun 16 14:00:46 servicedisco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 16 14:00:46 servicedisco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 16 14:00:46 servicedisco volumio[67544]: info: Initializing BBC Radios Jun 16 14:00:46 servicedisco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 16 14:00:46 servicedisco systemd[1]: Starting mpd.service - Music Player Daemon... Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: Creating Spotify config file Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:46 servicedisco volumio[67544]: info: [1750071646511] CoreMusicLibrary::Adding element YouTube2 Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:46 servicedisco volumio[67544]: Cannot find translation for source YouTube2 Jun 16 14:00:46 servicedisco dbus-daemon[656]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 16 14:00:46 servicedisco systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 16 14:00:46 servicedisco setdatetime-helper.sh[67731]: Time is already synchronized. Jun 16 14:00:46 servicedisco systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 16 14:00:46 servicedisco systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 16 14:00:46 servicedisco volumio[67544]: info: [1750071646607] CoreMusicLibrary::Adding element YouTube Music Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:46 servicedisco volumio[67544]: Cannot find translation for source YouTube2 Jun 16 14:00:46 servicedisco volumio[67544]: Cannot find translation for source YouTube Music Jun 16 14:00:46 servicedisco sudo[67772]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 16 14:00:46 servicedisco sudo[67772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 14:00:46 servicedisco sudo[67772]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:46 servicedisco volumio[67544]: info: Loading i18n strings for locale en Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 16 14:00:46 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:46 servicedisco volumio[67544]: info: FusionDsp - mixtype--------------------- Hardware Jun 16 14:00:46 servicedisco volumio[67544]: info: Volumio Calling Home Jun 16 14:00:46 servicedisco volumio[67544]: info: Preparing to generate the ALSA configuration file Jun 16 14:00:47 servicedisco volumio-remote-updater[668]: [2025-06-16 14:00:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750071644 101 Jun 16 14:00:47 servicedisco volumio[67544]: 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: 7 Jun 16 14:00:47 servicedisco volumio[67544]: info: Discovery: Connected to remote: 10.10.1.111 Jun 16 14:00:47 servicedisco volumio[67544]: info: Discovery: adding c029b43a-e024-45a8-ad48-8c9d6c316176 Jun 16 14:00:47 servicedisco volumio[67544]: info: Discovery: Found device ServiceDisco Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:47 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:47 servicedisco volumio[67544]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 16 14:00:47 servicedisco volumio[67544]: info: Reading ALSA contributions from plugins. Jun 16 14:00:47 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.0.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 16 14:00:47 servicedisco volumio[67544]: info: MPD Permissions set Jun 16 14:00:47 servicedisco volumio[67544]: info: MPD Permissions set Jun 16 14:00:47 servicedisco volumio[67544]: info: Upmpdcli Daemon Started Jun 16 14:00:47 servicedisco volumio[67544]: error: Upnp client error: Error: This socket has been ended by the other party Jun 16 14:00:47 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43:3000 from 10.10.1.111 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 16 14:00:47 servicedisco volumio[67544]: info: Volumio called home Jun 16 14:00:47 servicedisco volumio[67544]: info: Spotify config file written Jun 16 14:00:47 servicedisco volumio[67544]: info: Discovery: this is already registered, c029b43a-e024-45a8-ad48-8c9d6c316176 Jun 16 14:00:47 servicedisco volumio[67544]: info: Discovery: Found device ServiceDisco Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:47 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 16 14:00:47 servicedisco sudo[67785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 16 14:00:47 servicedisco sudo[67785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:47 servicedisco systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 16 14:00:47 servicedisco systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 16 14:00:47 servicedisco systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 16 14:00:47 servicedisco volumio[67544]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 16 14:00:47 servicedisco go-librespot[67787]: go-librespot daemon starting... Jun 16 14:00:47 servicedisco sudo[67785]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03:00" level=info msg="running go-librespot 0.2.0" Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03:00" level=debug msg="app state loaded" Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03:00" level=debug msg="stored credentials not found" Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:47 servicedisco volumio[67544]: info: No need to fix Spotify hosts Jun 16 14:00:47 servicedisco volumio[67544]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 16 14:00:47 servicedisco volumio[67544]: info: Received Get System Version Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 16 14:00:47 servicedisco volumio[67544]: info: Received Get System Info Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 16 14:00:47 servicedisco volumio[67544]: info: Discovery: Getting this device information Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:47 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetVisibleSources Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:47 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetQueue Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreStateMachine::getQueue Jun 16 14:00:47 servicedisco volumio[67544]: info: CorePlayQueue::getQueue Jun 16 14:00:47 servicedisco volumio[67544]: info: Listing playlists Jun 16 14:00:47 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 16 14:00:47 servicedisco volumio[67544]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03: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]" Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03: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]" Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03: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]" Jun 16 14:00:47 servicedisco go-librespot[67789]: time="2025-06-16T14:00:47+03:00" level=info msg="zeroconf server listening on port 39733" Jun 16 14:00:48 servicedisco volumio[67544]: info: Starting Shairport Sync Jun 16 14:00:48 servicedisco volumio[67544]: info: Starting Shairport Sync Jun 16 14:00:48 servicedisco volumio[67544]: info: Starting Shairport Sync Jun 16 14:00:48 servicedisco sudo[67813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:48 servicedisco sudo[67813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:48 servicedisco sudo[67815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:48 servicedisco sudo[67815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:48 servicedisco sudo[67817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:48 servicedisco sudo[67817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:48 servicedisco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 16 14:00:48 servicedisco systemd[1]: shairport-sync.service: Deactivated successfully. Jun 16 14:00:48 servicedisco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:48 servicedisco systemd[1]: shairport-sync.service: Consumed 1.945s CPU time. Jun 16 14:00:48 servicedisco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:48 servicedisco sudo[67813]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:48 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 16 14:00:48 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:48 servicedisco sudo[67815]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:48 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:48 servicedisco sudo[67817]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:48 servicedisco volumio[67544]: info: Asound.conf file unchanged, so no further update is needed Jun 16 14:00:48 servicedisco volumio[67544]: info: Output device has changed, restarting MPD Jun 16 14:00:48 servicedisco volumio[67544]: info: Output device has changed, restarting Shairport Sync Jun 16 14:00:48 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:48 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:48 servicedisco sudo[67823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 16 14:00:48 servicedisco sudo[67823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:48 servicedisco sudo[67825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 16 14:00:48 servicedisco sudo[67825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:48 servicedisco sudo[67823]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:48 servicedisco volumio[67544]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 16 14:00:48 servicedisco volumio[67544]: info: Shairport-Sync Started Jun 16 14:00:48 servicedisco volumio[67544]: Error adding Membership: Error: addMembership EINVAL Jun 16 14:00:48 servicedisco volumio[67544]: info: Shairport-Sync Started Jun 16 14:00:48 servicedisco systemd[1]: mpd.service: Deactivated successfully. Jun 16 14:00:48 servicedisco volumio[67544]: info: Shairport-Sync Started Jun 16 14:00:48 servicedisco systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 16 14:00:48 servicedisco systemd[1]: mpd.service: Consumed 2.271s CPU time. Jun 16 14:00:48 servicedisco systemd[1]: mpd.socket: Deactivated successfully. Jun 16 14:00:48 servicedisco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 16 14:00:48 servicedisco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 16 14:00:48 servicedisco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 16 14:00:48 servicedisco systemd[1]: Starting mpd.service - Music Player Daemon... Jun 16 14:00:48 servicedisco volumio[67544]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 16 14:00:48 servicedisco volumio[67544]: 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 Jun 16 14:00:48 servicedisco volumio[67544]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 16 14:00:48 servicedisco volumio[67544]: info: camilladsp service started and running in background, instance 1 Jun 16 14:00:48 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:48 servicedisco volumio[67544]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 <-AAAAAAAAAAAAA Jun 16 14:00:48 servicedisco volumio[67544]: info: FusionDsp loaded Jun 16 14:00:48 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 16 14:00:48 servicedisco volumio[67544]: error: FusionDsp - Socket connection not established Jun 16 14:00:48 servicedisco volumio[67544]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 16 14:00:48 servicedisco volumio[67544]: info: Adding Signal Path Element [object Object] Jun 16 14:00:48 servicedisco volumio[67544]: info: Adding fusiondspeq DSP Signal Path Element Jun 16 14:00:48 servicedisco volumio[67544]: info: FusionDsp - ---- installed callbackRead Jun 16 14:00:48 servicedisco volumio[67544]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 16 14:00:48 servicedisco sudo[67863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 16 14:00:48 servicedisco sudo[67863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:49 servicedisco sudo[67863]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:49 servicedisco sudo[67854]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 16 14:00:49 servicedisco sudo[67854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 16 14:00:49 servicedisco sudo[67854]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:49 servicedisco volumio[67544]: info: MPD Permissions set Jun 16 14:00:49 servicedisco volumio[67544]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:49 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:49 servicedisco volumio[67544]: info: Starting Shairport Sync Jun 16 14:00:49 servicedisco sudo[67877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 16 14:00:49 servicedisco sudo[67877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 16 14:00:49 servicedisco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 16 14:00:49 servicedisco systemd[1]: shairport-sync.service: Deactivated successfully. Jun 16 14:00:49 servicedisco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:49 servicedisco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 16 14:00:49 servicedisco sudo[67877]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:49 servicedisco volumio[67544]: info: Shairport-Sync Started Jun 16 14:00:49 servicedisco volumio[67544]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::volumioRetrievevolume Jun 16 14:00:49 servicedisco volumio[67544]: info: VolumeController:: Volume=25 Mute =false Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:49 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreStateMachine::pushState Jun 16 14:00:49 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:49 servicedisco volumio[67544]: info: CoreCommandRouter::volumioPushState Jun 16 14:00:51 servicedisco volumio[67544]: info: go-librespot daemon successfully initialized Jun 16 14:00:51 servicedisco volumio[67544]: verbose: New Socket.io Connection to 10.10.0.43 from 10.10.1.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 Jun 16 14:00:51 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetState Jun 16 14:00:51 servicedisco volumio[67544]: info: CorePlayQueue::getTrack 0 Jun 16 14:00:51 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 16 14:00:51 servicedisco volumio[67544]: info: CoreCommandRouter::volumioGetVisibleSources Jun 16 14:00:51 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 16 14:00:51 servicedisco volumio[67544]: info: Listing playlists Jun 16 14:00:52 servicedisco mpd[67867]: 2025-06-16T14:00:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 16 14:00:52 servicedisco systemd[1]: Started mpd.service - Music Player Daemon. Jun 16 14:00:52 servicedisco sudo[67825]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:52 servicedisco sudo[67738]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:52 servicedisco sudo[67749]: pam_unix(sudo:session): session closed for user root Jun 16 14:00:52 servicedisco volumio[67544]: error: MPD error: The expression evaluated to a falsy value: Jun 16 14:00:52 servicedisco volumio[67544]: assert.ok(self.idling) Jun 16 14:00:52 servicedisco volumio[67544]: error: The expression evaluated to a falsy value: Jun 16 14:00:52 servicedisco volumio[67544]: assert.ok(self.idling) Jun 16 14:00:52 servicedisco volumio[67544]: error: MPD error: The expression evaluated to a falsy value: Jun 16 14:00:52 servicedisco volumio[67544]: assert.ok(self.idling) Jun 16 14:00:52 servicedisco volumio[67544]: error: The expression evaluated to a falsy value: Jun 16 14:00:52 servicedisco volumio[67544]: assert.ok(self.idling) Jun 16 14:00:52 servicedisco volumio[67544]: info: MPD running with PID67867 Jun 16 14:00:52 servicedisco volumio[67544]: ,establishing connection Jun 16 14:00:52 servicedisco volumio[67544]: error: updateQueue error: null Jun 16 14:00:52 servicedisco volumio[67544]: info: Completed starting Core Plugins Jun 16 14:00:52 servicedisco volumio[67544]: info: ------------------------------------------- Jun 16 14:00:52 servicedisco volumio[67544]: info: ----- MyVolumio plugins startup ---- Jun 16 14:00:52 servicedisco volumio[67544]: info: ------------------------------------------- Jun 16 14:00:52 servicedisco volumio[67544]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 16 14:00:52 servicedisco volumio[67544]: error: updateQueue error: null Jun 16 14:00:54 servicedisco volumio[67544]: info: Initializing connection to go-librespot Websocket Jun 16 14:00:54 servicedisco go-librespot[67789]: time="2025-06-16T14:00:54+03:00" level=debug msg="new websocket client" Jun 16 14:00:54 servicedisco volumio[67544]: info: Connection to go-librespot Websocket established Jun 16 14:00:55 servicedisco volumio[67544]: Searching plugin music_service/webradio Jun 16 14:00:55 servicedisco volumio[67544]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 16 14:00:56 servicedisco volumio[67544]: Use XMLElement.setAttribute instead Jun 16 14:00:56 servicedisco volumio[67544]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 14:00:56 servicedisco volumio[67544]: TypeError: children[i].attr(...).value is not a function Jun 16 14:00:56 servicedisco volumio[67544]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 16 14:00:56 servicedisco volumio[67544]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 16 14:00:56 servicedisco volumio[67544]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 14:00:56 servicedisco sudo[67936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-16 13:59' Jun 16 14:00:56 servicedisco sudo[67936]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 12:51:02 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="bd8bb7a73fc911edbee270b9dc8f5d60"