Feb 07 11:13:00 volumiokurd volumio[1250]: info: BOOT COMPLETED Feb 07 11:13:00 volumiokurd volumio[1250]: info: sendMpdCommand stop took 254 milliseconds Feb 07 11:13:00 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand clear Feb 07 11:13:00 volumiokurd volumio[1250]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Feb 07 11:13:00 volumiokurd volumio[1250]: info: Feb 07 11:13:00 volumiokurd volumio[1250]: ---------------------------- MPD announces system playlist update Feb 07 11:13:00 volumiokurd volumio[1250]: info: Ignoring MPD Status Update Feb 07 11:13:00 volumiokurd volumio[1250]: info: sendMpdCommand clear took 153 milliseconds Feb 07 11:13:00 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf" Feb 07 11:13:00 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:00 volumiokurd volumio[1250]: SPOTIFY: SPOTIFY VOLUME 50 Feb 07 11:13:00 volumiokurd volumio[1250]: SPOTIFY: VOLUMIO VOLUME 100 Feb 07 11:13:00 volumiokurd volumio[1250]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 07 11:13:00 volumiokurd volumio[1250]: info: Setting Spotify Volume from Volumio: 100 Feb 07 11:13:00 volumiokurd volumio[1250]: info: Feb 07 11:13:00 volumiokurd volumio[1250]: ---------------------------- MPD announces system playlist update Feb 07 11:13:00 volumiokurd volumio[1250]: info: Ignoring MPD Status Update Feb 07 11:13:00 volumiokurd volumio[1250]: error: updateQueue error: null Feb 07 11:13:00 volumiokurd volumio[1250]: info: Feb 07 11:13:00 volumiokurd volumio[1250]: ---------------------------- MPD announces system playlist update Feb 07 11:13:00 volumiokurd volumio[1250]: info: Ignoring MPD Status Update Feb 07 11:13:00 volumiokurd volumio[1250]: info: ------------------------------ 495ms Feb 07 11:13:00 volumiokurd volumio[1250]: info: sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf" took 479 milliseconds Feb 07 11:13:00 volumiokurd volumio[1250]: info: ------------------------------ 20ms Feb 07 11:13:00 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand play Feb 07 11:13:01 volumiokurd volumio[1250]: info: Feb 07 11:13:01 volumiokurd volumio[1250]: ---------------------------- MPD announces system playlist update Feb 07 11:13:01 volumiokurd volumio[1250]: info: Ignoring MPD Status Update Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 11:13:01 volumiokurd volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 07 11:13:01 volumiokurd volumio[1250]: info: Received Get System Version Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 11:13:01 volumiokurd volumio[1250]: info: Received Get System Info Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 11:13:01 volumiokurd volumio[1250]: info: Discovery: Getting this device information Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioGetState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 11:13:01 volumiokurd volumio[1250]: info: Feb 07 11:13:01 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:01 volumiokurd volumio[1250]: info: Feb 07 11:13:01 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:01 volumiokurd volumio[1250]: info: ------------------------------ 204ms Feb 07 11:13:01 volumiokurd volumio[1250]: info: sendMpdCommand play took 204 milliseconds Feb 07 11:13:01 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:01 volumiokurd volumio[1250]: info: ------------------------------ 16ms Feb 07 11:13:01 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:01 volumiokurd volumio[1250]: info: Feb 07 11:13:01 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:01 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:01 volumiokurd volumio[1250]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Feb 07 11:13:01 volumiokurd volumio[1250]: info: FusionDsp - ---- read samplerate from file: 384000 Feb 07 11:13:01 volumiokurd volumio[1250]: info: camilladsp stopping service pid 2384... Feb 07 11:13:01 volumiokurd volumio[1250]: grep: /proc/2384/cmdline: binary file matches Feb 07 11:13:01 volumiokurd volumio[1250]: info: camilladsp service terminated, instance 1 Feb 07 11:13:01 volumiokurd volumio[1250]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 07 11:13:01 volumiokurd volumio[1250]: info: camilladsp service started and running in background, instance 1 Feb 07 11:13:01 volumiokurd volumio[1250]: info: sendMpdCommand status took 181 milliseconds Feb 07 11:13:01 volumiokurd volumio[1250]: info: sendMpdCommand status took 180 milliseconds Feb 07 11:13:01 volumiokurd volumio[1250]: info: sendMpdCommand status took 175 milliseconds Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:01 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 07 11:13:01 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 07 11:13:01 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:01 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":338,"duration":244,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Over Town","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf","trackType":"dsf"} Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: CURRENT POSITION 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Feb 07 11:13:01 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":338,"duration":244,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Over Town","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf","trackType":"dsf"} Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: CURRENT POSITION 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus play Feb 07 11:13:01 volumiokurd volumio[1250]: info: Received an update from plugin. extracting info from payload Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":338,"duration":244,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Over Town","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf","trackType":"dsf"} Feb 07 11:13:01 volumiokurd volumio[1250]: verbose: CURRENT POSITION 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus play Feb 07 11:13:01 volumiokurd volumio[1250]: info: Received an update from plugin. extracting info from payload Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:01 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:01 volumiokurd volumio[1250]: info: ------------------------------ 274ms Feb 07 11:13:01 volumiokurd volumio[1250]: info: ------------------------------ 341ms Feb 07 11:13:01 volumiokurd volumio[1250]: info: ------------------------------ 335ms Feb 07 11:13:01 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:01 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:01 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:01 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:02 volumiokurd volumio[1250]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioGetState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPause Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::pause Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::stPlaybackTimer Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::servicePause Feb 07 11:13:02 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePause Feb 07 11:13:02 volumiokurd volumio[1250]: info: ControllerMpd::pause Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand pause Feb 07 11:13:02 volumiokurd volumio[1250]: info: mpdhttpout --- Volumio set on pause Feb 07 11:13:02 volumiokurd volumio[1250]: info: Feb 07 11:13:02 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:02 volumiokurd volumio[1250]: info: sendMpdCommand pause took 107 milliseconds Feb 07 11:13:02 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:02 volumiokurd volumio[1250]: info: Feb 07 11:13:02 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:02 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:02 volumiokurd volumio[1250]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 07 11:13:02 volumiokurd volumio[1250]: info: sendMpdCommand status took 3 milliseconds Feb 07 11:13:02 volumiokurd volumio[1250]: info: sendMpdCommand status took 2 milliseconds Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:02 volumiokurd volumio[1250]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Feb 07 11:13:02 volumiokurd volumio[1250]: info: Sending Spotify command with payload to local API: /player/volume Feb 07 11:13:02 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 07 11:13:02 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:02 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":593,"duration":244,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Over Town","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf","trackType":"dsf"} Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: CURRENT POSITION 938 Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService pause Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus pause Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::stPlaybackTimer Feb 07 11:13:02 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":593,"duration":244,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Over Town","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf","trackType":"dsf"} Feb 07 11:13:02 volumiokurd volumio[1250]: verbose: CURRENT POSITION 938 Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService pause Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus pause Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:02 volumiokurd volumio[1250]: info: CoreStateMachine::stPlaybackTimer Feb 07 11:13:02 volumiokurd volumio[1250]: info: ------------------------------ 226ms Feb 07 11:13:02 volumiokurd volumio[1250]: info: ------------------------------ 225ms Feb 07 11:13:02 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:02 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:02 volumiokurd volumio[1250]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 07 11:13:04 volumiokurd sudo[2443]: volumio : unable to resolve host volumiokurd: System error Feb 07 11:13:04 volumiokurd sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 11:13:04 volumiokurd sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 11:13:04 volumiokurd systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 11:13:04 volumiokurd systemd[1]: mpd.service: Deactivated successfully. Feb 07 11:13:04 volumiokurd systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 11:13:04 volumiokurd systemd[1]: mpd.service: Consumed 1.942s CPU time. Feb 07 11:13:04 volumiokurd systemd[1]: mpd.socket: Deactivated successfully. Feb 07 11:13:04 volumiokurd systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 11:13:04 volumiokurd systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 11:13:04 volumiokurd systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 11:13:04 volumiokurd systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 11:13:04 volumiokurd sudo[2446]: root : unable to resolve host volumiokurd: System error Feb 07 11:13:04 volumiokurd sudo[2446]: sudo: unable to resolve host volumiokurd: System error Feb 07 11:13:04 volumiokurd sudo[2446]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 11:13:04 volumiokurd sudo[2446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 11:13:04 volumiokurd sudo[2446]: pam_unix(sudo:session): session closed for user root Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 11:13:04 volumiokurd volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 07 11:13:04 volumiokurd volumio[1250]: info: Received Get System Version Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 11:13:04 volumiokurd volumio[1250]: info: Received Get System Info Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 11:13:04 volumiokurd volumio[1250]: info: Discovery: Getting this device information Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioGetState Feb 07 11:13:04 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:04 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 11:13:06 volumiokurd mpd[2448]: 2026-02-07T11:13:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 11:13:06 volumiokurd systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 11:13:06 volumiokurd sudo[2443]: pam_unix(sudo:session): session closed for user root Feb 07 11:13:06 volumiokurd volumio[1250]: error: updateQueue error: null Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioNext Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::next Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::stop Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::updateTrackBlock Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrackBlock Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::stPlaybackTimer Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::serviceStop Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 938 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::serviceStop Feb 07 11:13:13 volumiokurd volumio[1250]: info: ControllerMpd::stop Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 11:13:13 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand stop took 40 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::play index undefined Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::startPlaybackTimer Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf Feb 07 11:13:13 volumiokurd volumio[1250]: info: Setting Volume to 100 automatically for DSD Feb 07 11:13:13 volumiokurd volumio[1250]: info: VolumeController::SetAlsaVolume100 Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::updateTrackBlock Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrackBlock Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand stop took 51 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand clear Feb 07 11:13:13 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:13 volumiokurd volumio[1250]: info: Feb 07 11:13:13 volumiokurd volumio[1250]: ---------------------------- MPD announces system playlist update Feb 07 11:13:13 volumiokurd volumio[1250]: info: Ignoring MPD Status Update Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand clear took 12 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf" Feb 07 11:13:13 volumiokurd volumio[1250]: error: updateQueue error: null Feb 07 11:13:13 volumiokurd volumio[1250]: info: Feb 07 11:13:13 volumiokurd volumio[1250]: ---------------------------- MPD announces system playlist update Feb 07 11:13:13 volumiokurd volumio[1250]: info: Ignoring MPD Status Update Feb 07 11:13:13 volumiokurd volumio[1250]: info: ------------------------------ 52ms Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf" took 51 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand play Feb 07 11:13:13 volumiokurd volumio[1250]: info: ------------------------------ 2ms Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand play took 2 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: info: Feb 07 11:13:13 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:13 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:13 volumiokurd volumio[1250]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Feb 07 11:13:13 volumiokurd volumio[1250]: info: FusionDsp - ---- read samplerate from file: 384000 Feb 07 11:13:13 volumiokurd volumio[1250]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 07 11:13:13 volumiokurd volumio[1250]: info: Feb 07 11:13:13 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:13 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand status took 86 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand status took 2 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:13 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Feb 07 11:13:13 volumiokurd volumio[1250]: info: ------------------------------ 90ms Feb 07 11:13:13 volumiokurd kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Feb 07 11:13:13 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:13 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":338,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:13:13 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus play Feb 07 11:13:13 volumiokurd volumio[1250]: info: Received an update from plugin. extracting info from payload Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:13 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:13 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:14 volumiokurd volumio[1250]: info: ------------------------------ 201ms Feb 07 11:13:14 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:14 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:14 volumiokurd volumio[1250]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 07 11:13:16 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPrevious Feb 07 11:13:16 volumiokurd volumio[1250]: info: CoreStateMachine::previous Feb 07 11:13:16 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:16 volumiokurd volumio[1250]: info: ControllerMpd::seek Feb 07 11:13:17 volumiokurd volumio[1250]: error: null Feb 07 11:13:17 volumiokurd volumio[1250]: info: Feb 07 11:13:17 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:17 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:17 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:17 volumiokurd volumio[1250]: info: sendMpdCommand status took 8 milliseconds Feb 07 11:13:17 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:17 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:17 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 11:13:17 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:17 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:17 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:17 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:13:17 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus play Feb 07 11:13:17 volumiokurd volumio[1250]: info: Received an update from plugin. extracting info from payload Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:17 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:17 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:17 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:17 volumiokurd volumio[1250]: info: ------------------------------ 212ms Feb 07 11:13:17 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:17 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPause Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreStateMachine::pause Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreStateMachine::stPlaybackTimer Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreStateMachine::servicePause Feb 07 11:13:34 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePause Feb 07 11:13:34 volumiokurd volumio[1250]: info: ControllerMpd::pause Feb 07 11:13:34 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand pause Feb 07 11:13:34 volumiokurd volumio[1250]: info: Feb 07 11:13:34 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:34 volumiokurd volumio[1250]: info: sendMpdCommand pause took 22 milliseconds Feb 07 11:13:34 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:34 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:34 volumiokurd volumio[1250]: info: sendMpdCommand status took 0 milliseconds Feb 07 11:13:34 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:34 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:34 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 07 11:13:34 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:34 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:34 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:34 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15483,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:13:34 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService pause Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus pause Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:34 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:34 volumiokurd volumio[1250]: info: CoreStateMachine::stPlaybackTimer Feb 07 11:13:34 volumiokurd volumio[1250]: info: ------------------------------ 77ms Feb 07 11:13:34 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:34 volumiokurd volumio[1250]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPlay Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreStateMachine::play index undefined Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 11:13:42 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreStateMachine::startPlaybackTimer Feb 07 11:13:42 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:42 volumiokurd volumio[1250]: info: ControllerMpd::resume Feb 07 11:13:42 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand play Feb 07 11:13:42 volumiokurd volumio[1250]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Feb 07 11:13:42 volumiokurd volumio[1250]: info: FusionDsp - ---- read samplerate from file: 384000 Feb 07 11:13:42 volumiokurd volumio[1250]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 07 11:13:42 volumiokurd volumio[1250]: info: Feb 07 11:13:42 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: player Feb 07 11:13:42 volumiokurd volumio[1250]: info: sendMpdCommand play took 66 milliseconds Feb 07 11:13:42 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:13:42 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:13:42 volumiokurd kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Feb 07 11:13:42 volumiokurd volumio[1250]: info: sendMpdCommand status took 38 milliseconds Feb 07 11:13:42 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:13:42 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:13:42 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 11:13:42 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:13:42 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:13:42 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:42 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15824,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:13:42 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus pause Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:13:42 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:13:42 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:13:42 volumiokurd volumio[1250]: info: ------------------------------ 87ms Feb 07 11:13:42 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:13:42 volumiokurd volumio[1250]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 11:13:49 volumiokurd volumio[1250]: info: Retrieving Cloud Streaming UI Feb 07 11:13:49 volumiokurd volumio[1250]: info: Getting Tidal Cloud Configuration Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 11:13:49 volumiokurd volumio[1250]: info: Getting Qobuz Cloud Configuration Feb 07 11:13:49 volumiokurd volumio[1250]: info: Asking plugin for UI Config Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 11:13:49 volumiokurd volumio[1250]: info: Getting Spotify Cloud Configuration Feb 07 11:13:49 volumiokurd volumio[1250]: info: Asking plugin for UI Config Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 11:13:49 volumiokurd volumio[1250]: info: Saving Spotify Acccount Feb 07 11:13:49 volumiokurd volumio[1250]: info: Got Tidal Cloud Configuration Feb 07 11:13:49 volumiokurd volumio[1250]: info: Got it Feb 07 11:13:49 volumiokurd volumio[1250]: info: Got it Feb 07 11:13:49 volumiokurd volumio[1250]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 11:13:49 volumiokurd volumio[1250]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 11:13:49 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 11:13:50 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 11:13:50 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 07 11:13:54 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 07 11:13:54 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 11:13:55 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 07 11:13:58 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 11:14:00 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 07 11:14:00 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand update Feb 07 11:14:00 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:14:00 volumiokurd volumio[1250]: info: Feb 07 11:14:00 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: update Feb 07 11:14:00 volumiokurd volumio[1250]: info: sendMpdCommand update took 41 milliseconds Feb 07 11:14:00 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:14:00 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:14:00 volumiokurd volumio[1250]: info: sendMpdCommand status took 44 milliseconds Feb 07 11:14:00 volumiokurd volumio[1250]: info: sendMpdCommand status took 43 milliseconds Feb 07 11:14:00 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:14:01 volumiokurd volumio[1250]: info: Command Router : Notfying DB Updatetrue Feb 07 11:14:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:14:01 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:14:01 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 07 11:14:01 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:14:01 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:14:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:01 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32760,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:14:01 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus play Feb 07 11:14:01 volumiokurd volumio[1250]: info: Received an update from plugin. extracting info from payload Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:14:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:14:01 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:01 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:14:01 volumiokurd volumio[1250]: info: ------------------------------ 487ms Feb 07 11:14:01 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:14:01 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:14:02 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 11:14:02 volumiokurd volumio[1250]: info: CoreCommandRouter::Close All Modals sent Feb 07 11:14:06 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 11:14:10 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 11:14:11 volumiokurd volumio[1250]: info: Disabling MyMusic plugin upnp Feb 07 11:14:11 volumiokurd sudo[2595]: volumio : unable to resolve host volumiokurd: System error Feb 07 11:14:11 volumiokurd sudo[2595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 07 11:14:11 volumiokurd sudo[2595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 11:14:11 volumiokurd systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Feb 07 11:14:11 volumiokurd volumio[1250]: info: MPD Database updated - AlbumList cache refreshed Feb 07 11:14:11 volumiokurd volumio[1250]: info: Feb 07 11:14:11 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: database Feb 07 11:14:11 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:14:11 volumiokurd volumio[1250]: info: Feb 07 11:14:11 volumiokurd volumio[1250]: ---------------------------- MPD announces state update: update Feb 07 11:14:11 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:14:11 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:14:11 volumiokurd volumio[1250]: info: ControllerMpd::getState Feb 07 11:14:11 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Feb 07 11:14:12 volumiokurd volumio[1250]: info: sendMpdCommand status took 499 milliseconds Feb 07 11:14:12 volumiokurd volumio[1250]: info: sendMpdCommand status took 498 milliseconds Feb 07 11:14:12 volumiokurd volumio[1250]: info: sendMpdCommand status took 499 milliseconds Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:14:12 volumiokurd volumio[1250]: info: Command Router : Notfying DB Updatefalse Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::Close All Modals sent Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: ControllerMpd::parseState Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 11:14:12 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 07 11:14:12 volumiokurd volumio[1250]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: ControllerMpd::parseTrackInfo Feb 07 11:14:12 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41336,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus play Feb 07 11:14:12 volumiokurd volumio[1250]: info: Received an update from plugin. extracting info from payload Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: ControllerMpd::pushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::servicePushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41336,"duration":241,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 07 11:14:12 volumiokurd volumio[1250]: verbose: CURRENT POSITION 939 Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::syncState stateService play Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::syncState currentStatus play Feb 07 11:14:12 volumiokurd volumio[1250]: info: Received an update from plugin. extracting info from payload Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreStateMachine::pushState Feb 07 11:14:12 volumiokurd volumio[1250]: info: CorePlayQueue::getTrack 939 Feb 07 11:14:12 volumiokurd volumio[1250]: info: CoreCommandRouter::volumioPushState Feb 07 11:14:13 volumiokurd volumio[1250]: info: ------------------------------ 1084ms Feb 07 11:14:13 volumiokurd volumio[1250]: info: ------------------------------ 1085ms Feb 07 11:14:13 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:14:13 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:14:13 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:14:13 volumiokurd volumio[1250]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 11:14:13 volumiokurd volumio[1250]: info: Enabling MyMusic plugin upnp Feb 07 11:14:13 volumiokurd volumio[1250]: info: Enabling plugin upnp Feb 07 11:14:13 volumiokurd volumio[1250]: info: Loading plugin "upnp"... Feb 07 11:14:13 volumiokurd volumio[1250]: info: [1770452053192] Starting Upmpd Daemon Feb 07 11:14:13 volumiokurd volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 11:14:13 volumiokurd volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 11:14:13 volumiokurd volumio[1250]: Error: listen EADDRINUSE: address already in use :::6599 Feb 07 11:14:13 volumiokurd volumio[1250]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Feb 07 11:14:13 volumiokurd volumio[1250]: at listenInCluster (node:net:1920:12) Feb 07 11:14:13 volumiokurd volumio[1250]: at Server.listen (node:net:2008:7) Feb 07 11:14:13 volumiokurd volumio[1250]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 07 11:14:13 volumiokurd volumio[1250]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 07 11:14:13 volumiokurd volumio[1250]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 07 11:14:13 volumiokurd volumio[1250]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 07 11:14:13 volumiokurd volumio[1250]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 07 11:14:13 volumiokurd volumio[1250]: code: 'EADDRINUSE', Feb 07 11:14:13 volumiokurd volumio[1250]: errno: -98, Feb 07 11:14:13 volumiokurd volumio[1250]: syscall: 'listen', Feb 07 11:14:13 volumiokurd volumio[1250]: address: '::', Feb 07 11:14:13 volumiokurd volumio[1250]: port: 6599 Feb 07 11:14:13 volumiokurd volumio[1250]: } Feb 07 11:14:13 volumiokurd volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 11:14:17 volumiokurd sudo[2629]: volumio : unable to resolve host volumiokurd: System error Feb 07 11:14:17 volumiokurd sudo[2629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 11:13' Feb 07 11:14:17 volumiokurd sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"