Feb 22 00:57:09 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:09 volumio volumio[1135]: info: Listing playlists Feb 22 00:57:09 volumio volumio[1135]: info: Listing playlists Feb 22 00:57:19 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:19 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:29 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:29 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:29 volumio volumio[1135]: info: Listing playlists Feb 22 00:57:29 volumio volumio[1135]: info: Listing playlists Feb 22 00:57:37 volumio sudo[2138]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:57:37 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:57:37 volumio sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:57:37 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Feb 22 00:57:37 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:57:37 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Feb 22 00:57:37 volumio volumio[1135]: verbose: New Socket.io Connection to 192.168.88.33 from 192.168.88.62 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 00:57:38 volumio sudo[2144]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:57:38 volumio sudo[2144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:57:38 volumio sudo[2146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:57:38 volumio sudo[2144]: pam_unix(sudo:session): session closed for user root Feb 22 00:57:38 volumio sudo[2146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:57:38 volumio sudo[2146]: pam_unix(sudo:session): session closed for user root Feb 22 00:57:38 volumio volumio[1135]: verbose: New Socket.io Connection to 192.168.88.33 from 192.168.88.62 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:38 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 00:57:38 volumio volumio[1135]: info: Received Get System Info Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:57:38 volumio volumio[1135]: info: Discovery: Getting this device information Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:38 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:38 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:38 volumio volumio[1135]: info: Listing playlists Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 00:57:38 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 00:57:39 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:39 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:39 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:39 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:39 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 00:57:40 volumio volumio[1135]: info: Received Get System Info Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:57:40 volumio volumio[1135]: info: Discovery: Getting this device information Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::play index undefined Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3 Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand stop took 3 milliseconds Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:40 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand clear took 5 milliseconds Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3" Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:40 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:40 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:40 volumio volumio[1135]: error: updateQueue error: null Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:40 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 15ms Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand add "NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3" took 12 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 10ms Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 7ms Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:40 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:40 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 11ms Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand play took 7 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 7ms Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 4ms Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand status took 19 milliseconds Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand status took 23 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand status took 21 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dance Planet Tendance Intro","artist":null,"album":"DANCE PLANET TENDANCE","uri":"NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3","trackType":"mp3"} Feb 22 00:57:40 volumio volumio[1135]: verbose: CURRENT POSITION 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 41ms Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:40 volumio volumio[1135]: info: Feb 22 00:57:40 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand status took 21 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand status took 6 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand status took 4 milliseconds Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dance Planet Tendance Intro","artist":null,"album":"DANCE PLANET TENDANCE","uri":"NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3","trackType":"mp3"} Feb 22 00:57:40 volumio volumio[1135]: verbose: CURRENT POSITION 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:40 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dance Planet Tendance Intro","artist":null,"album":"DANCE PLANET TENDANCE","uri":"NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3","trackType":"mp3"} Feb 22 00:57:40 volumio volumio[1135]: verbose: CURRENT POSITION 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:40 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 134ms Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 133ms Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 90 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 89 milliseconds Feb 22 00:57:40 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 88 milliseconds Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:40 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dance Planet Tendance Intro","artist":null,"album":"DANCE PLANET TENDANCE","uri":"NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3","trackType":"mp3"} Feb 22 00:57:40 volumio volumio[1135]: verbose: CURRENT POSITION 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:40 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dance Planet Tendance Intro","artist":null,"album":"DANCE PLANET TENDANCE","uri":"NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3","trackType":"mp3"} Feb 22 00:57:40 volumio volumio[1135]: verbose: CURRENT POSITION 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:40 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":147,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dance Planet Tendance Intro","artist":null,"album":"DANCE PLANET TENDANCE","uri":"NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3","trackType":"mp3"} Feb 22 00:57:40 volumio volumio[1135]: verbose: CURRENT POSITION 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:40 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:40 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:40 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 219ms Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 203ms Feb 22 00:57:40 volumio volumio[1135]: info: ------------------------------ 201ms Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioNext Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::next Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::serviceStop Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Feb 22 00:57:43 volumio volumio[1135]: info: ControllerMpd::stop Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 00:57:43 volumio volumio[1135]: info: Feb 22 00:57:43 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:43 volumio volumio[1135]: info: sendMpdCommand stop took 13 milliseconds Feb 22 00:57:43 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::play index undefined Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks NAS/server/Dance Planet TenDance/02-G-Spott ”Sadness” (Original mix).mp3 Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Feb 22 00:57:43 volumio volumio[1135]: info: sendMpdCommand status took 4 milliseconds Feb 22 00:57:43 volumio volumio[1135]: info: sendMpdCommand stop took 3 milliseconds Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 00:57:43 volumio volumio[1135]: info: Feb 22 00:57:43 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:43 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:43 volumio volumio[1135]: info: Feb 22 00:57:43 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:43 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:43 volumio volumio[1135]: info: Feb 22 00:57:43 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:43 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:43 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 22 00:57:43 volumio volumio[1135]: info: sendMpdCommand clear took 5 milliseconds Feb 22 00:57:43 volumio volumio[1135]: info: sendMpdCommand status took 5 milliseconds Feb 22 00:57:43 volumio volumio[1135]: info: sendMpdCommand status took 3 milliseconds Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "NAS/server/Dance Planet TenDance/02-G-Spott ”Sadness” (Original mix).mp3" Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:43 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:43 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: 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":"Dance Planet Tendance Intro","artist":null,"album":"DANCE PLANET TENDANCE","uri":"NAS/server/Dance Planet TenDance/01-Dance Planet Tendance Intro.mp3","trackType":"mp3"} Feb 22 00:57:43 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::syncState stateService stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: No code Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 00:57:43 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::syncState stateService stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: No code Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 22 00:57:43 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::syncState stateService stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:43 volumio volumio[1135]: info: No code Feb 22 00:57:43 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:43 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:43 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 124ms Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 117ms Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 116ms Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:44 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:44 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:44 volumio volumio[1135]: error: updateQueue error: null Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:44 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 122ms Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand add "NAS/server/Dance Planet TenDance/02-G-Spott ”Sadness” (Original mix).mp3" took 119 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 7ms Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 6ms Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:44 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Feb 22 00:57:44 volumio volumio[1135]: info: Ignoring MPD Status Update Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 59ms Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand play took 56 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 57ms Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 55ms Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand status took 14 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand status took 16 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand status took 16 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand status took 4 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: [407B blob data] Feb 22 00:57:44 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 27ms Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:44 volumio volumio[1135]: info: Feb 22 00:57:44 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::getState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand status took 4 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand status took 3 milliseconds Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseState Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: [407B blob data] Feb 22 00:57:44 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:44 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: [407B blob data] Feb 22 00:57:44 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:44 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: [415B blob data] Feb 22 00:57:44 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:44 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 143ms Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 142ms Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 132ms Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 22 00:57:44 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 113 milliseconds Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:44 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: [415B blob data] Feb 22 00:57:44 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:44 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: ControllerMpd::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: [415B blob data] Feb 22 00:57:44 volumio volumio[1135]: verbose: CURRENT POSITION 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Feb 22 00:57:44 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:57:44 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:44 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 188ms Feb 22 00:57:44 volumio volumio[1135]: info: ------------------------------ 187ms Feb 22 00:57:45 volumio volumio[1135]: info: CoreCommandRouter::volumioGetQueue Feb 22 00:57:45 volumio volumio[1135]: info: CoreStateMachine::getQueue Feb 22 00:57:45 volumio volumio[1135]: info: CorePlayQueue::getQueue Feb 22 00:57:48 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 00:57:49 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:49 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:49 volumio volumio[1135]: info: Listing playlists Feb 22 00:57:49 volumio volumio[1135]: info: Listing playlists Feb 22 00:57:49 volumio ntpd[921]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 22 00:57:54 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 00:57:54 volumio volumio[1135]: info: CURURI: music-library Feb 22 00:57:54 volumio volumio[1135]: info: Preload queue cleared Feb 22 00:57:55 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 00:57:55 volumio volumio[1135]: info: CURURI: music-library/NAS Feb 22 00:57:56 volumio volumio[1135]: info: Preload queue cleared Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 00:57:56 volumio volumio[1135]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 00:57:56 volumio volumio[1135]: info: Received Get System Version Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 00:57:56 volumio volumio[1135]: info: Received Get System Info Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:57:56 volumio volumio[1135]: info: Discovery: Getting this device information Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:57:56 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:57:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 00:57:56 volumio volumio[1135]: info: CURURI: music-library/NAS/server Feb 22 00:57:56 volumio volumio[1135]: info: Preload queue cleared Feb 22 00:57:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 00:57:59 volumio volumio[1135]: info: CURURI: music-library/NAS/server/The Offspring Feb 22 00:57:59 volumio volumio[1135]: info: Preload queue cleared Feb 22 00:58:00 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Feb 22 00:58:00 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:58:00 volumio volumio[1135]: info: Preload queue cleared Feb 22 00:58:00 volumio volumio[1135]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::ClearQueue Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::stop Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Feb 22 00:58:00 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::pushState Feb 22 00:58:00 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:58:00 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:58:00 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::serviceStop Feb 22 00:58:00 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Feb 22 00:58:00 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Feb 22 00:58:00 volumio volumio[1135]: info: ControllerMpd::stop Feb 22 00:58:00 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 00:58:00 volumio volumio[1135]: info: CorePlayQueue::clearPlayQueue Feb 22 00:58:00 volumio volumio[1135]: info: CorePlayQueue::saveQueue Feb 22 00:58:00 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Feb 22 00:58:00 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Feb 22 00:58:00 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Feb 22 00:58:00 volumio volumio[1135]: info: Preload queue cleared Feb 22 00:58:00 volumio volumio[1135]: info: Adding Item to queue: music-library/NAS/server/The Offspring/1989 - The Offspring (EICP 11) Feb 22 00:58:00 volumio volumio[1135]: info: Exploding uri music-library/NAS/server/The Offspring/1989 - The Offspring (EICP 11) in service mpd Feb 22 00:58:01 volumio nmbd[1002]: [2026/02/22 00:58:01.675222, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 22 00:58:01 volumio nmbd[1002]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.88.33 for name WORKGROUP<1d>. Feb 22 00:58:01 volumio nmbd[1002]: This response was from IP 192.168.88.6, reporting an IP address of 192.168.88.6. Feb 22 00:58:01 volumio volumio[1135]: info: Feb 22 00:58:01 volumio volumio[1135]: ---------------------------- MPD announces state update: player Feb 22 00:58:01 volumio volumio[1135]: info: ALBUMART /albumart?cacheid=875&web=The%20Offspring/The%20Offspring/extralarge&path=%2Fmnt%2FNAS%2Fserver%2FThe%20Offspring%2F1989%20-%20The%20Offspring%20(EICP%2011)%2FThe%20Offspring%20-%20The%20Offspring.cue&metadata=false Feb 22 00:58:01 volumio volumio[1135]: info: URI /mnt/NAS/server/The Offspring/1989 - The Offspring (EICP 11)/The Offspring - The Offspring.cue Feb 22 00:58:01 volumio volumio[1135]: info: ALBUMART /albumart?cacheid=875&web=The%20Offspring/The%20Offspring/extralarge&path=%2Fmnt%2FNAS%2Fserver%2FThe%20Offspring%2F1989%20-%20The%20Offspring%20(EICP%2011)%2FThe%20Offspring%20-%20The%20Offspring.cue&metadata=false Feb 22 00:58:01 volumio volumio[1135]: info: URI /mnt/NAS/server/The Offspring/1989 - The Offspring (EICP 11)/The Offspring - The Offspring.cue Feb 22 00:58:01 volumio volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 00:58:01 volumio volumio[1135]: Error: Unable to resolve or reject the same promise twice Feb 22 00:58:01 volumio volumio[1135]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 22 00:58:01 volumio volumio[1135]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Feb 22 00:58:01 volumio volumio[1135]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 22 00:58:01 volumio volumio[1135]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 22 00:58:01 volumio volumio[1135]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 22 00:58:01 volumio volumio[1135]: at Socket.emit (node:events:514:28) Feb 22 00:58:01 volumio volumio[1135]: at addChunk (node:internal/streams/readable:343:12) Feb 22 00:58:01 volumio volumio[1135]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 22 00:58:01 volumio volumio[1135]: at Readable.push (node:internal/streams/readable:253:10) Feb 22 00:58:01 volumio volumio[1135]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 22 00:58:01 volumio volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 00:58:02 volumio sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 00:57' Feb 22 00:58:02 volumio sudo[2247]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"