Feb 26 12:11:00 volumiokurd volumio[1227]: verbose: New Socket.io Connection to 192.168.0.165 from 192.168.0.170 UA: Mozilla/5.0 (Linux; Android 13; M2101K7BNY Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 26 12:11:00 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetState Feb 26 12:11:01 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 0 Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 12:11:01 volumiokurd volumio[1227]: info: Received Get System Info Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 12:11:01 volumiokurd volumio[1227]: info: Discovery: Getting this device information Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetState Feb 26 12:11:01 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 0 Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetState Feb 26 12:11:01 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 0 Feb 26 12:11:01 volumiokurd volumio[1227]: info: Listing playlists Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetQueue Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreStateMachine::getQueue Feb 26 12:11:01 volumiokurd volumio[1227]: info: CorePlayQueue::getQueue Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 12:11:01 volumiokurd volumio[1227]: info: Discovery: Getting this device information Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetState Feb 26 12:11:01 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 0 Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 12:11:01 volumiokurd volumio[1227]: verbose: New Socket.io Connection to 192.168.0.165:3000 from 192.168.0.170 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 12:11:01 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 12:11:03 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:03 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:03 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:03 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:03 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:06 volumiokurd volumio[1227]: info: Preload queue cleared Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioAddQueueItems Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::addQueueItems Feb 26 12:11:06 volumiokurd volumio[1227]: info: CorePlayQueue::addQueueItems Feb 26 12:11:06 volumiokurd volumio[1227]: info: Preload queue cleared Feb 26 12:11:06 volumiokurd volumio[1227]: info: Adding Item to queue: albums://Secret%20Service/Cutting%20Corners Feb 26 12:11:06 volumiokurd volumio[1227]: info: Exploding uri albums://Secret%20Service/Cutting%20Corners in service mpd Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushQueue Feb 26 12:11:06 volumiokurd volumio[1227]: info: CorePlayQueue::saveQueue Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::updateTrackBlock Feb 26 12:11:06 volumiokurd volumio[1227]: info: CorePlayQueue::getTrackBlock Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPlay Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::play index 1797 Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::stop Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::play index undefined Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:06 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::startPlaybackTimer Feb 26 12:11:06 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:06 volumiokurd volumio[1227]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf Feb 26 12:11:06 volumiokurd volumio[1227]: info: Setting Volume to 100 automatically for DSD Feb 26 12:11:06 volumiokurd volumio[1227]: info: VolumeController::SetAlsaVolume100 Feb 26 12:11:06 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:06 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:06 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:06 volumiokurd volumio[1227]: info: sendMpdCommand stop took 21 milliseconds Feb 26 12:11:06 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 12:11:06 volumiokurd volumio[1227]: info: Feb 26 12:11:06 volumiokurd volumio[1227]: ---------------------------- MPD announces system playlist update Feb 26 12:11:06 volumiokurd volumio[1227]: info: Ignoring MPD Status Update Feb 26 12:11:06 volumiokurd volumio[1227]: info: sendMpdCommand clear took 46 milliseconds Feb 26 12:11:06 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf" Feb 26 12:11:06 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:06 volumiokurd volumio[1227]: SPOTIFY: SPOTIFY VOLUME 50 Feb 26 12:11:06 volumiokurd volumio[1227]: SPOTIFY: VOLUMIO VOLUME 100 Feb 26 12:11:06 volumiokurd volumio[1227]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 26 12:11:06 volumiokurd volumio[1227]: info: Setting Spotify Volume from Volumio: 100 Feb 26 12:11:06 volumiokurd volumio[1227]: error: updateQueue error: null Feb 26 12:11:06 volumiokurd volumio[1227]: info: Feb 26 12:11:06 volumiokurd volumio[1227]: ---------------------------- MPD announces system playlist update Feb 26 12:11:06 volumiokurd volumio[1227]: info: Ignoring MPD Status Update Feb 26 12:11:06 volumiokurd volumio[1227]: info: ------------------------------ 274ms Feb 26 12:11:06 volumiokurd volumio[1227]: info: sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf" took 271 milliseconds Feb 26 12:11:06 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand play Feb 26 12:11:06 volumiokurd volumio[1227]: info: ------------------------------ 6ms Feb 26 12:11:06 volumiokurd volumio[1227]: info: sendMpdCommand play took 4 milliseconds Feb 26 12:11:07 volumiokurd volumio[1227]: info: Feb 26 12:11:07 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:07 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate from file: 384000 Feb 26 12:11:07 volumiokurd volumio[1227]: info: camilladsp stopping service pid 2233... Feb 26 12:11:07 volumiokurd kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Feb 26 12:11:07 volumiokurd volumio[1227]: info: camilladsp service terminated, instance 1 Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - Effects disabled Feb 26 12:11:07 volumiokurd volumio[1227]: info: camilladsp service started and running in background, instance 1 Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate from file: 384000 Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 12:11:07 volumiokurd volumio[1227]: info: FusionDsp - Effects disabled Feb 26 12:11:07 volumiokurd volumio[1227]: info: Feb 26 12:11:07 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:07 volumiokurd volumio[1227]: info: sendMpdCommand status took 142 milliseconds Feb 26 12:11:07 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:07 volumiokurd volumio[1227]: info: sendMpdCommand status took 6 milliseconds Feb 26 12:11:07 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:07 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"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 26 12:11:07 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1797 Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService play Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus stop Feb 26 12:11:07 volumiokurd volumio[1227]: info: ------------------------------ 157ms Feb 26 12:11:07 volumiokurd volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object] Feb 26 12:11:07 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:07 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:07 volumiokurd volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252,"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 26 12:11:07 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1797 Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService play Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus play Feb 26 12:11:07 volumiokurd volumio[1227]: info: Received an update from plugin. extracting info from payload Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:07 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:07 volumiokurd volumio[1227]: info: ------------------------------ 66ms Feb 26 12:11:07 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:07 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:07 volumiokurd volumio[1227]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 26 12:11:08 volumiokurd volumio[1227]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Feb 26 12:11:08 volumiokurd volumio[1227]: info: Sending Spotify command with payload to local API: /player/volume Feb 26 12:11:08 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:08 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:08 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:08 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:08 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:13 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:13 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:13 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:13 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:13 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:17 volumiokurd ntpd[1061]: PROTO: 185.211.244.47 unlink local addr 192.168.0.165 -> Feb 26 12:11:19 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:19 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:19 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:19 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:19 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioNext Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::next Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::stop Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::stPlaybackTimer Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::updateTrackBlock Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrackBlock Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::serviceStop Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1797 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::serviceStop Feb 26 12:11:20 volumiokurd volumio[1227]: info: ControllerMpd::stop Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 12:11:20 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:20 volumiokurd volumio[1227]: info: Feb 26 12:11:20 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand stop took 21 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::play index undefined Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::startPlaybackTimer Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf Feb 26 12:11:20 volumiokurd volumio[1227]: info: Setting Volume to 100 automatically for DSD Feb 26 12:11:20 volumiokurd volumio[1227]: info: VolumeController::SetAlsaVolume100 Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::updateTrackBlock Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrackBlock Feb 26 12:11:20 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand status took 68 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand stop took 43 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 12:11:20 volumiokurd volumio[1227]: info: Feb 26 12:11:20 volumiokurd volumio[1227]: ---------------------------- MPD announces system playlist update Feb 26 12:11:20 volumiokurd volumio[1227]: info: Ignoring MPD Status Update Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand clear took 24 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf" Feb 26 12:11:20 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: 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":"A1 Over Town","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A1 Over Town.dsf","trackType":"dsf"} Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService stop Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus stop Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: No code Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: ------------------------------ 139ms Feb 26 12:11:20 volumiokurd volumio[1227]: error: updateQueue error: null Feb 26 12:11:20 volumiokurd volumio[1227]: info: Feb 26 12:11:20 volumiokurd volumio[1227]: ---------------------------- MPD announces system playlist update Feb 26 12:11:20 volumiokurd volumio[1227]: info: Ignoring MPD Status Update Feb 26 12:11:20 volumiokurd volumio[1227]: info: ------------------------------ 48ms Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand add "USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf" took 45 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand play Feb 26 12:11:20 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:20 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:20 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:20 volumiokurd volumio[1227]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 26 12:11:20 volumiokurd volumio[1227]: info: Feb 26 12:11:20 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:20 volumiokurd volumio[1227]: info: ------------------------------ 40ms Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand play took 38 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:20 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Feb 26 12:11:20 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate from file: 384000 Feb 26 12:11:20 volumiokurd volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 12:11:20 volumiokurd volumio[1227]: info: FusionDsp - Effects disabled Feb 26 12:11:20 volumiokurd volumio[1227]: info: Feb 26 12:11:20 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:20 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand status took 13 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand status took 6 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:20 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: 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 26 12:11:20 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService play Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus stop Feb 26 12:11:20 volumiokurd volumio[1227]: info: ------------------------------ 24ms Feb 26 12:11:20 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 12:11:20 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:20 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: 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 26 12:11:20 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService play Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus play Feb 26 12:11:20 volumiokurd volumio[1227]: info: Received an update from plugin. extracting info from payload Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:20 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:20 volumiokurd volumio[1227]: info: ------------------------------ 48ms Feb 26 12:11:20 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:20 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:20 volumiokurd volumio[1227]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 26 12:11:24 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:24 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:24 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:24 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:24 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:29 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:29 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:29 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:29 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:29 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:30 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 26 12:11:30 volumiokurd volumio[1227]: info: CURURI: music-library Feb 26 12:11:30 volumiokurd volumio[1227]: info: Preload queue cleared Feb 26 12:11:32 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 26 12:11:32 volumiokurd volumio[1227]: info: CURURI: music-library/USB Feb 26 12:11:32 volumiokurd volumio[1227]: info: Preload queue cleared Feb 26 12:11:33 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 26 12:11:33 volumiokurd volumio[1227]: info: CURURI: music-library/USB/Новый_том Feb 26 12:11:33 volumiokurd volumio[1227]: info: Preload queue cleared Feb 26 12:11:34 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:34 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:34 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:34 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:34 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:34 volumiokurd volumio[1227]: info: Preload queue cleared Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioAddQueueItems Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::addQueueItems Feb 26 12:11:34 volumiokurd volumio[1227]: info: CorePlayQueue::addQueueItems Feb 26 12:11:34 volumiokurd volumio[1227]: info: Preload queue cleared Feb 26 12:11:34 volumiokurd volumio[1227]: info: Adding Item to queue: music-library/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang) Feb 26 12:11:34 volumiokurd volumio[1227]: info: Exploding uri music-library/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang) in service mpd Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FA1%20Superman.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A1 Superman.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FA2%20Don't%20Believe%20What%20You%20Read.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A2 Don't Believe What You Read.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FA3%20Baby%20Me%20Baby.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A3 Baby Me Baby.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FA4%20I%20Found%20You%20Love.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A4 I Found You Love.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FA5%20Answer%20Me.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A5 Answer Me.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FB1%20My%20Heart%20Belongs%20To%20Me.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/B1 My Heart Belongs To Me.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FB2%20Cabin%20Fever.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/B2 Cabin Fever.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FB3%20Love%20Comes%20From%20Unexpected%20Places.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/B3 Love Comes From Unexpected Places.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FB4%20New%20York%20State%20Of%20Mind.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/B4 New York State Of Mind.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: ALBUMART /albumart?cacheid=857&web=Barbra%20Streisand/Streisand%20Superman%2C(CBS-JC%2034830%2CUSA)/large&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FBarbra%20Streisand%20-%20Streisand%20Superman%20-%201977%2C(USA)%2CDSF(tracks)%2C(ART-9%2BNG%2BTang)%2FB5%20Lullaby%20For%20Myself.dsf&metadata=false Feb 26 12:11:34 volumiokurd volumio[1227]: info: URI /mnt/USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/B5 Lullaby For Myself.dsf Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushQueue Feb 26 12:11:34 volumiokurd systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Feb 26 12:11:34 volumiokurd systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Feb 26 12:11:34 volumiokurd systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Feb 26 12:11:34 volumiokurd systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Feb 26 12:11:34 volumiokurd volumio[1227]: info: CorePlayQueue::saveQueue Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::updateTrackBlock Feb 26 12:11:34 volumiokurd volumio[1227]: info: CorePlayQueue::getTrackBlock Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPlay Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::play index 1807 Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::stop Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::stPlaybackTimer Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::updateTrackBlock Feb 26 12:11:34 volumiokurd volumio[1227]: info: CorePlayQueue::getTrackBlock Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:34 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreStateMachine::serviceStop Feb 26 12:11:34 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1798 Feb 26 12:11:34 volumiokurd volumio[1227]: info: CoreCommandRouter::serviceStop Feb 26 12:11:34 volumiokurd volumio[1227]: info: ControllerMpd::stop Feb 26 12:11:34 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 12:11:35 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:35 volumiokurd volumio[1227]: info: Feb 26 12:11:35 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand stop took 264 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::play index undefined Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::startPlaybackTimer Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A1 Superman.dsf Feb 26 12:11:35 volumiokurd volumio[1227]: info: Setting Volume to 100 automatically for DSD Feb 26 12:11:35 volumiokurd volumio[1227]: info: VolumeController::SetAlsaVolume100 Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand status took 67 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand stop took 55 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 12:11:35 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:35 volumiokurd volumio[1227]: info: Feb 26 12:11:35 volumiokurd volumio[1227]: ---------------------------- MPD announces system playlist update Feb 26 12:11:35 volumiokurd volumio[1227]: info: Ignoring MPD Status Update Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand clear took 35 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A1 Superman.dsf" Feb 26 12:11:35 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: 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":"A2 Fire Into Ice","artist":"Secret Service","album":"Cutting Corners","uri":"USB/Новый_том/Secret Service - Cutting Corners/A2 Fire Into Ice.dsf","trackType":"dsf"} Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService stop Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus stop Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: No code Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: ------------------------------ 146ms Feb 26 12:11:35 volumiokurd volumio[1227]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 26 12:11:35 volumiokurd volumio[1227]: error: updateQueue error: null Feb 26 12:11:35 volumiokurd volumio[1227]: info: Feb 26 12:11:35 volumiokurd volumio[1227]: ---------------------------- MPD announces system playlist update Feb 26 12:11:35 volumiokurd volumio[1227]: info: Ignoring MPD Status Update Feb 26 12:11:35 volumiokurd volumio[1227]: info: ------------------------------ 45ms Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand add "USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A1 Superman.dsf" took 43 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand play Feb 26 12:11:35 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:35 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:35 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:35 volumiokurd volumio[1227]: info: ------------------------------ 29ms Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand play took 28 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: info: Feb 26 12:11:35 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:35 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:35 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Feb 26 12:11:35 volumiokurd volumio[1227]: info: FusionDsp - ---- read samplerate from file: 384000 Feb 26 12:11:35 volumiokurd volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 12:11:35 volumiokurd volumio[1227]: info: FusionDsp - Effects disabled Feb 26 12:11:35 volumiokurd volumio[1227]: info: Feb 26 12:11:35 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: player Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand status took 8 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand status took 3 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:35 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Superman","artist":"Barbra Streisand","album":"Streisand Superman,(CBS-JC 34830,USA)","uri":"USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A1 Superman.dsf","trackType":"dsf"} Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService play Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus stop Feb 26 12:11:35 volumiokurd volumio[1227]: info: ------------------------------ 21ms Feb 26 12:11:35 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:35 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A1 Superman","artist":"Barbra Streisand","album":"Streisand Superman,(CBS-JC 34830,USA)","uri":"USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A1 Superman.dsf","trackType":"dsf"} Feb 26 12:11:35 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService play Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus play Feb 26 12:11:35 volumiokurd volumio[1227]: info: Received an update from plugin. extracting info from payload Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:35 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:35 volumiokurd volumio[1227]: info: ------------------------------ 51ms Feb 26 12:11:35 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:35 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:35 volumiokurd volumio[1227]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 26 12:11:39 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:39 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:39 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:39 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:39 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:44 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:44 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:44 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:44 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:44 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:49 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:49 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:49 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:49 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:49 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:51 volumiokurd volumio[1227]: info: Retrieving Cloud Streaming UI Feb 26 12:11:51 volumiokurd volumio[1227]: info: Getting Tidal Cloud Configuration Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:51 volumiokurd volumio[1227]: info: Getting Qobuz Cloud Configuration Feb 26 12:11:51 volumiokurd volumio[1227]: info: Asking plugin for UI Config Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:51 volumiokurd volumio[1227]: info: Getting Spotify Cloud Configuration Feb 26 12:11:51 volumiokurd volumio[1227]: info: Asking plugin for UI Config Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:51 volumiokurd volumio[1227]: info: Saving Spotify Acccount Feb 26 12:11:51 volumiokurd volumio[1227]: info: Got Tidal Cloud Configuration Feb 26 12:11:51 volumiokurd volumio[1227]: info: Got it Feb 26 12:11:51 volumiokurd volumio[1227]: info: Got it Feb 26 12:11:51 volumiokurd volumio[1227]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:11:51 volumiokurd volumio[1227]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 12:11:51 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 12:11:52 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:11:52 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand update Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:54 volumiokurd volumio[1227]: info: Feb 26 12:11:54 volumiokurd volumio[1227]: ---------------------------- MPD announces state update: update Feb 26 12:11:54 volumiokurd volumio[1227]: info: sendMpdCommand update took 7 milliseconds Feb 26 12:11:54 volumiokurd volumio[1227]: info: ControllerMpd::getState Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand status Feb 26 12:11:54 volumiokurd volumio[1227]: info: sendMpdCommand status took 2 milliseconds Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:54 volumiokurd volumio[1227]: info: Command Router : Notfying DB Updatetrue Feb 26 12:11:54 volumiokurd volumio[1227]: info: sendMpdCommand status took 376 milliseconds Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: ControllerMpd::parseState Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 12:11:54 volumiokurd volumio[1227]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: ControllerMpd::parseTrackInfo Feb 26 12:11:54 volumiokurd volumio[1227]: info: ControllerMpd::pushState Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreCommandRouter::servicePushState Feb 26 12:11:54 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19237,"duration":169,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"A1 Superman","artist":"Barbra Streisand","album":"Streisand Superman,(CBS-JC 34830,USA)","uri":"USB/Новый_том/Barbra Streisand - Streisand Superman - 1977,(USA),DSF(tracks),(ART-9+NG+Tang)/A1 Superman.dsf","trackType":"dsf"} Feb 26 12:11:54 volumiokurd volumio[1227]: verbose: CURRENT POSITION 1807 Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreStateMachine::syncState stateService play Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreStateMachine::syncState currentStatus play Feb 26 12:11:54 volumiokurd volumio[1227]: info: Received an update from plugin. extracting info from payload Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:54 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreStateMachine::pushState Feb 26 12:11:54 volumiokurd volumio[1227]: info: CorePlayQueue::getTrack 1807 Feb 26 12:11:54 volumiokurd volumio[1227]: info: CoreCommandRouter::volumioPushState Feb 26 12:11:54 volumiokurd volumio[1227]: info: ------------------------------ 432ms Feb 26 12:11:54 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:54 volumiokurd volumio[1227]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 12:11:55 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:55 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:55 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:11:55 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:11:55 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:11:56 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:11:56 volumiokurd volumio[1227]: info: CoreCommandRouter::Close All Modals sent Feb 26 12:12:00 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:12:00 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:00 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:00 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:00 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:00 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:12:04 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:12:05 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:05 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:05 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:05 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:05 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:12:07 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 26 12:12:07 volumiokurd volumio[1227]: verbose: ControllerMpd::sendMpdCommand rescan Feb 26 12:12:07 volumiokurd volumio[1227]: info: sendMpdCommand rescan took 4 milliseconds Feb 26 12:12:08 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:12:10 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:10 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:10 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:10 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:10 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:12:12 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:12:15 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:15 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:15 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:15 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:15 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:12:16 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:12:19 volumiokurd volumio[1227]: info: Disabling MyMusic plugin upnp Feb 26 12:12:19 volumiokurd sudo[4152]: volumio : unable to resolve host volumiokurd: System error Feb 26 12:12:19 volumiokurd sudo[4152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 26 12:12:19 volumiokurd sudo[4152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 12:12:19 volumiokurd systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Feb 26 12:12:20 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 12:12:20 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:20 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:20 volumiokurd volumio[1227]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 12:12:20 volumiokurd volumio[1227]: info: Discovery: Restarting Browsing Feb 26 12:12:20 volumiokurd volumio[1227]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 12:12:21 volumiokurd volumio[1227]: info: Enabling MyMusic plugin upnp Feb 26 12:12:21 volumiokurd volumio[1227]: info: Enabling plugin upnp Feb 26 12:12:21 volumiokurd volumio[1227]: info: Loading plugin "upnp"... Feb 26 12:12:21 volumiokurd volumio[1227]: info: [1772097141247] Starting Upmpd Daemon Feb 26 12:12:21 volumiokurd volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 12:12:21 volumiokurd volumio[1227]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 12:12:21 volumiokurd volumio[1227]: Error: listen EADDRINUSE: address already in use :::6599 Feb 26 12:12:21 volumiokurd volumio[1227]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Feb 26 12:12:21 volumiokurd volumio[1227]: at listenInCluster (node:net:1920:12) Feb 26 12:12:21 volumiokurd volumio[1227]: at Server.listen (node:net:2008:7) Feb 26 12:12:21 volumiokurd volumio[1227]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 26 12:12:21 volumiokurd volumio[1227]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 26 12:12:21 volumiokurd volumio[1227]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 26 12:12:21 volumiokurd volumio[1227]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 26 12:12:21 volumiokurd volumio[1227]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 26 12:12:21 volumiokurd volumio[1227]: code: 'EADDRINUSE', Feb 26 12:12:21 volumiokurd volumio[1227]: errno: -98, Feb 26 12:12:21 volumiokurd volumio[1227]: syscall: 'listen', Feb 26 12:12:21 volumiokurd volumio[1227]: address: '::', Feb 26 12:12:21 volumiokurd volumio[1227]: port: 6599 Feb 26 12:12:21 volumiokurd volumio[1227]: } Feb 26 12:12:21 volumiokurd volumio[1227]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 12:12:21 volumiokurd sudo[4171]: volumio : unable to resolve host volumiokurd: System error Feb 26 12:12:21 volumiokurd sudo[4171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 12:11' Feb 26 12:12:21 volumiokurd sudo[4171]: 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"