-- Logs begin at Mon 2025-06-30 16:56:57 CEST, end at Thu 2025-07-03 23:05:26 CEST. -- Jul 03 23:04:03 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:03 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:03 ms002 sudo[17943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 23:04:03 ms002 sudo[17943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 23:04:03 ms002 sudo[17943]: pam_unix(sudo:session): session closed for user root Jul 03 23:04:03 ms002 sudo[17947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 23:04:03 ms002 sudo[17947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 23:04:03 ms002 sudo[17947]: pam_unix(sudo:session): session closed for user root Jul 03 23:04:03 ms002 volumio[846]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.176 UA: Mozilla/5.0 (Linux; Android 15; SM-S928B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 03 23:04:03 ms002 sudo[17950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 23:04:03 ms002 sudo[17950]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 23:04:03 ms002 sudo[17950]: pam_unix(sudo:session): session closed for user root Jul 03 23:04:03 ms002 sudo[17953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 23:04:03 ms002 sudo[17953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 03 23:04:03 ms002 sudo[17953]: pam_unix(sudo:session): session closed for user root Jul 03 23:04:04 ms002 volumio[846]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.176 UA: Mozilla/5.0 (Linux; Android 15; SM-S928B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:04 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::volumioGetQueue Jul 03 23:04:04 ms002 volumio[846]: info: CoreStateMachine::getQueue Jul 03 23:04:04 ms002 volumio[846]: info: CorePlayQueue::getQueue Jul 03 23:04:04 ms002 volumio[846]: info: Listing playlists Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 23:04:04 ms002 volumio[846]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 03 23:04:04 ms002 volumio[846]: info: Received Get System Info Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 23:04:04 ms002 volumio[846]: info: Discovery: Getting this device information Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:04 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:04 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 23:04:04 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 03 23:04:05 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 03 23:04:05 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 23:04:05 ms002 volumio[846]: info: Received Get System Info Jul 03 23:04:05 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 23:04:05 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 23:04:05 ms002 volumio[846]: info: Discovery: Getting this device information Jul 03 23:04:05 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:05 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:05 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 23:04:06 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 23:04:06 ms002 volumio[846]: info: Received Get System Info Jul 03 23:04:06 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 23:04:06 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 23:04:06 ms002 volumio[846]: info: Discovery: Getting this device information Jul 03 23:04:06 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:06 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:06 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 23:04:14 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 03 23:04:21 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 23:04:21 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 23:04:21 ms002 volumio[846]: info: Discovery: Getting this device information Jul 03 23:04:21 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:21 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:21 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 23:04:22 ms002 volumio[846]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 23:04:22 ms002 volumio[846]: info: Received Get System Version Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 23:04:22 ms002 volumio[846]: info: Received Get System Info Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 23:04:22 ms002 volumio[846]: info: Discovery: Getting this device information Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 23:04:22 ms002 volumio[846]: info: Preload queue cleared Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::ClearQueue Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::stop Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::stPlaybackTimer Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::updateTrackBlock Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::getTrackBlock Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:04:22 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:04:22 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::serviceStop Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::serviceStop Jul 03 23:04:22 ms002 volumio[846]: info: ControllerMpd::stop Jul 03 23:04:22 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::clearPlayQueue Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::saveQueue Jul 03 23:04:22 ms002 volumio[846]: info: CoreCommandRouter::volumioPushQueue Jul 03 23:04:22 ms002 volumio[846]: info: CoreStateMachine::addQueueItems Jul 03 23:04:22 ms002 volumio[846]: info: CorePlayQueue::addQueueItems Jul 03 23:04:22 ms002 volumio[846]: info: Preload queue cleared Jul 03 23:04:22 ms002 volumio[846]: info: Adding Item to queue: music-library/NAS/Musik/Music for Cats and freinds V.1 Jul 03 23:04:22 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1 in service mpd Jul 03 23:04:23 ms002 volumio[846]: info: Jul 03 23:04:23 ms002 volumio[846]: ---------------------------- MPD announces state update: player Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F01%20-%20Dreams.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F02%20-%20Coming%20Home.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/02 - Coming Home.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F03%20-%20Sunshine.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/03 - Sunshine.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F04%20-%20Open%20Mind.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/04 - Open Mind.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F05%20-%20Silence.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/05 - Silence.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F06%20-%20Rainy%20Day.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/06 - Rainy Day.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F07%20-%20Timeless.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/07 - Timeless.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F08%20-%20Images.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/08 - Images.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F09%20-%20Blue%20Sky.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/09 - Blue Sky.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=Various%20Artists/Atmospheric%20Synthesizer%2C%20Vol.%201%20%5BBudget%5D/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F10%20-%20Easy%20Listening.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=//extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F11%20-%20Dimensions.mp3&metadata=false Jul 03 23:04:23 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/11 - Dimensions.mp3 Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand stop took 544 milliseconds Jul 03 23:04:23 ms002 volumio[846]: info: ControllerMpd::getState Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand status Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioPushQueue Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::saveQueue Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::updateTrackBlock Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrackBlock Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioPlay Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::play index 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::stop Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::play index undefined Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::startPlaybackTimer Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::clearAddPlayTracks NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3 Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand status took 19 milliseconds Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand stop took 5 milliseconds Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::parseState Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Jul 03 23:04:23 ms002 volumio[846]: info: Jul 03 23:04:23 ms002 volumio[846]: ---------------------------- MPD announces system playlist update Jul 03 23:04:23 ms002 volumio[846]: info: Ignoring MPD Status Update Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand clear took 3 milliseconds Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::parseTrackInfo Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand add "NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3" Jul 03 23:04:23 ms002 volumio[846]: info: ControllerMpd::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::servicePushState Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: 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":"Anything is Possible","artist":"Alexander","album":"Take Your Chance","uri":"NAS/Musik/Alexander Album/08_alexander_-_anything_is_possible-mod.mp3","trackType":"mp3"} Jul 03 23:04:23 ms002 volumio[846]: verbose: CURRENT POSITION 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::syncState stateService stop Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::syncState currentStatus stop Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: No code Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: ------------------------------ 78ms Jul 03 23:04:23 ms002 volumio[846]: error: updateQueue error: null Jul 03 23:04:23 ms002 volumio[846]: info: Jul 03 23:04:23 ms002 volumio[846]: ---------------------------- MPD announces system playlist update Jul 03 23:04:23 ms002 volumio[846]: info: Ignoring MPD Status Update Jul 03 23:04:23 ms002 volumio[846]: info: ------------------------------ 47ms Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand add "NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3" took 46 milliseconds Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand play Jul 03 23:04:23 ms002 volumio[846]: info: ------------------------------ 37ms Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand play took 36 milliseconds Jul 03 23:04:23 ms002 volumio[846]: info: Jul 03 23:04:23 ms002 volumio[846]: ---------------------------- MPD announces state update: player Jul 03 23:04:23 ms002 volumio[846]: info: ControllerMpd::getState Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand status Jul 03 23:04:23 ms002 volumio[846]: info: Jul 03 23:04:23 ms002 volumio[846]: ---------------------------- MPD announces state update: player Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand status took 31 milliseconds Jul 03 23:04:23 ms002 volumio[846]: info: ControllerMpd::getState Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand status Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::parseState Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand status took 4 milliseconds Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::parseState Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::parseTrackInfo Jul 03 23:04:23 ms002 volumio[846]: info: ControllerMpd::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::servicePushState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Dreams","artist":"petsandmusic","album":"Music for Cats and Friends Vol. 1","uri":"NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3","trackType":"mp3"} Jul 03 23:04:23 ms002 volumio[846]: verbose: CURRENT POSITION 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::syncState stateService play Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::syncState currentStatus stop Jul 03 23:04:23 ms002 volumio[846]: info: ------------------------------ 49ms Jul 03 23:04:23 ms002 volumio[846]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 03 23:04:23 ms002 volumio[846]: verbose: ControllerMpd::parseTrackInfo Jul 03 23:04:23 ms002 volumio[846]: info: ControllerMpd::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::servicePushState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297,"duration":318,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"Dreams","artist":"petsandmusic","album":"Music for Cats and Friends Vol. 1","uri":"NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3","trackType":"mp3"} Jul 03 23:04:23 ms002 volumio[846]: verbose: CURRENT POSITION 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::syncState stateService play Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::syncState currentStatus play Jul 03 23:04:23 ms002 volumio[846]: info: Received an update from plugin. extracting info from payload Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:04:23 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:04:23 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:23 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:23 ms002 volumio[846]: info: ------------------------------ 55ms Jul 03 23:04:36 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 03 23:04:36 ms002 volumio[846]: info: CURURI: music-library/NAS/Musik/Music for Cats and freinds V.1 Jul 03 23:04:36 ms002 systemd[1]: Starting Cleanup of Temporary Directories... Jul 03 23:04:36 ms002 volumio[846]: info: Preload queue cleared Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/11 - Dimensions.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/02 - Coming Home.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/03 - Sunshine.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/04 - Open Mind.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/05 - Silence.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/06 - Rainy Day.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/07 - Timeless.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/08 - Images.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Preloading song: music-library/NAS/Musik/Music for Cats and freinds V.1/09 - Blue Sky.mp3 Jul 03 23:04:36 ms002 volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/11 - Dimensions.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=//extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F11%20-%20Dimensions.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/11 - Dimensions.mp3 Jul 03 23:04:36 ms002 systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jul 03 23:04:36 ms002 systemd[1]: Started Cleanup of Temporary Directories. Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=Various%20Artists/Atmospheric%20Synthesizer%2C%20Vol.%201%20%5BBudget%5D/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F10%20-%20Easy%20Listening.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F01%20-%20Dreams.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/01 - Dreams.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/02 - Coming Home.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F02%20-%20Coming%20Home.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/02 - Coming Home.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/03 - Sunshine.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F03%20-%20Sunshine.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/03 - Sunshine.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/04 - Open Mind.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F04%20-%20Open%20Mind.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/04 - Open Mind.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/05 - Silence.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F05%20-%20Silence.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/05 - Silence.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/06 - Rainy Day.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F06%20-%20Rainy%20Day.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/06 - Rainy Day.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/07 - Timeless.mp3 in service mpd Jul 03 23:04:36 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F07%20-%20Timeless.mp3&metadata=false Jul 03 23:04:36 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/07 - Timeless.mp3 Jul 03 23:04:36 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/08 - Images.mp3 in service mpd Jul 03 23:04:37 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F08%20-%20Images.mp3&metadata=false Jul 03 23:04:37 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/08 - Images.mp3 Jul 03 23:04:37 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Music for Cats and freinds V.1/09 - Blue Sky.mp3 in service mpd Jul 03 23:04:37 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FMusic%20for%20Cats%20and%20freinds%20V.1%2F09%20-%20Blue%20Sky.mp3&metadata=false Jul 03 23:04:37 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Music for Cats and freinds V.1/09 - Blue Sky.mp3 Jul 03 23:04:51 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 23:04:51 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 23:04:51 ms002 volumio[846]: info: Discovery: Getting this device information Jul 03 23:04:51 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:04:51 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:04:51 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 23:05:10 ms002 volumio[846]: info: Preload queue cleared Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::ClearQueue Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::stop Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::stPlaybackTimer Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::updateTrackBlock Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::getTrackBlock Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:05:10 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:05:10 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::serviceStop Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::serviceStop Jul 03 23:05:10 ms002 volumio[846]: info: ControllerMpd::stop Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::clearPlayQueue Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::saveQueue Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::volumioPushQueue Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::addQueueItems Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::addQueueItems Jul 03 23:05:10 ms002 volumio[846]: info: Preload queue cleared Jul 03 23:05:10 ms002 volumio[846]: info: Adding Item to queue: music-library/NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3 Jul 03 23:05:10 ms002 volumio[846]: info: Using cached record of: music-library/NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3 Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::volumioPushQueue Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::saveQueue Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::updateTrackBlock Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::getTrackBlock Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::volumioPlay Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::play index 0 Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::stop Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::play index undefined Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:10 ms002 volumio[846]: info: CoreStateMachine::startPlaybackTimer Jul 03 23:05:10 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 23:05:10 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::clearAddPlayTracks NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3 Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Jul 03 23:05:10 ms002 volumio[846]: info: Jul 03 23:05:10 ms002 volumio[846]: ---------------------------- MPD announces state update: player Jul 03 23:05:10 ms002 volumio[846]: info: sendMpdCommand stop took 37 milliseconds Jul 03 23:05:10 ms002 volumio[846]: info: ControllerMpd::getState Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand status Jul 03 23:05:10 ms002 volumio[846]: info: sendMpdCommand stop took 13 milliseconds Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Jul 03 23:05:10 ms002 volumio[846]: info: Jul 03 23:05:10 ms002 volumio[846]: ---------------------------- MPD announces system playlist update Jul 03 23:05:10 ms002 volumio[846]: info: Ignoring MPD Status Update Jul 03 23:05:10 ms002 volumio[846]: info: sendMpdCommand status took 4 milliseconds Jul 03 23:05:10 ms002 volumio[846]: info: sendMpdCommand clear took 3 milliseconds Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::parseState Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand add "NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3" Jul 03 23:05:10 ms002 volumio[846]: error: updateQueue error: null Jul 03 23:05:10 ms002 volumio[846]: info: Jul 03 23:05:10 ms002 volumio[846]: ---------------------------- MPD announces system playlist update Jul 03 23:05:10 ms002 volumio[846]: info: Ignoring MPD Status Update Jul 03 23:05:10 ms002 volumio[846]: info: ------------------------------ 5ms Jul 03 23:05:10 ms002 volumio[846]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 03 23:05:10 ms002 volumio[846]: info: sendMpdCommand add "NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3" took 4 milliseconds Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::parseTrackInfo Jul 03 23:05:10 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand play Jul 03 23:05:10 ms002 volumio[846]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 03 23:05:10 ms002 volumio[846]: info: ------------------------------ 13ms Jul 03 23:05:10 ms002 volumio[846]: info: ------------------------------ 5ms Jul 03 23:05:10 ms002 volumio[846]: info: sendMpdCommand play took 6 milliseconds Jul 03 23:05:11 ms002 volumio[846]: info: Jul 03 23:05:11 ms002 volumio[846]: ---------------------------- MPD announces state update: player Jul 03 23:05:11 ms002 volumio[846]: info: ControllerMpd::getState Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand status Jul 03 23:05:11 ms002 volumio[846]: info: Jul 03 23:05:11 ms002 volumio[846]: ---------------------------- MPD announces state update: player Jul 03 23:05:11 ms002 volumio[846]: info: sendMpdCommand status took 29 milliseconds Jul 03 23:05:11 ms002 volumio[846]: info: ControllerMpd::getState Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand status Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::parseState Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 23:05:11 ms002 volumio[846]: info: sendMpdCommand status took 5 milliseconds Jul 03 23:05:11 ms002 volumio[846]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::parseState Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::parseTrackInfo Jul 03 23:05:11 ms002 volumio[846]: info: ControllerMpd::pushState Jul 03 23:05:11 ms002 volumio[846]: info: CoreCommandRouter::servicePushState Jul 03 23:05:11 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:11 ms002 volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aurora","artist":"Various Artists","album":"Atmospheric Synthesizer, Vol. 1 [Budget]","uri":"NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3","trackType":"mp3"} Jul 03 23:05:11 ms002 volumio[846]: verbose: CURRENT POSITION 0 Jul 03 23:05:11 ms002 volumio[846]: info: CoreStateMachine::syncState stateService play Jul 03 23:05:11 ms002 volumio[846]: info: CoreStateMachine::syncState currentStatus stop Jul 03 23:05:11 ms002 volumio[846]: info: ------------------------------ 48ms Jul 03 23:05:11 ms002 volumio[846]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 03 23:05:11 ms002 volumio[846]: verbose: ControllerMpd::parseTrackInfo Jul 03 23:05:11 ms002 volumio[846]: info: ControllerMpd::pushState Jul 03 23:05:11 ms002 volumio[846]: info: CoreCommandRouter::servicePushState Jul 03 23:05:11 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:11 ms002 volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":215,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aurora","artist":"Various Artists","album":"Atmospheric Synthesizer, Vol. 1 [Budget]","uri":"NAS/Musik/Music for Cats and freinds V.1/10 - Easy Listening.mp3","trackType":"mp3"} Jul 03 23:05:11 ms002 volumio[846]: verbose: CURRENT POSITION 0 Jul 03 23:05:11 ms002 volumio[846]: info: CoreStateMachine::syncState stateService play Jul 03 23:05:11 ms002 volumio[846]: info: CoreStateMachine::syncState currentStatus play Jul 03 23:05:11 ms002 volumio[846]: info: Received an update from plugin. extracting info from payload Jul 03 23:05:11 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:05:11 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:11 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 23:05:11 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:05:11 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:05:11 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:05:11 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:05:11 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:11 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:05:11 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:11 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:05:11 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:05:11 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:05:11 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:05:11 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:11 ms002 volumio[846]: info: ------------------------------ 56ms Jul 03 23:05:21 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 23:05:21 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 23:05:21 ms002 volumio[846]: info: Discovery: Getting this device information Jul 03 23:05:21 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:05:21 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:21 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 23:05:24 ms002 volumio[846]: info: Preload queue cleared Jul 03 23:05:24 ms002 volumio[846]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::ClearQueue Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::stop Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::stPlaybackTimer Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::updateTrackBlock Jul 03 23:05:24 ms002 volumio[846]: info: CorePlayQueue::getTrackBlock Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::pushState Jul 03 23:05:24 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:24 ms002 volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 23:05:24 ms002 volumio[846]: info: CoreCommandRouter::volumioPushState Jul 03 23:05:24 ms002 volumio[846]: info: MRS: Pushing multiroomSync output update for this device Jul 03 23:05:24 ms002 volumio[846]: info: MRS: Pushing multiroomSync output Jul 03 23:05:24 ms002 volumio[846]: info: CoreCommandRouter::volumioGetState Jul 03 23:05:24 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::serviceStop Jul 03 23:05:24 ms002 volumio[846]: info: CorePlayQueue::getTrack 0 Jul 03 23:05:24 ms002 volumio[846]: info: CoreCommandRouter::serviceStop Jul 03 23:05:24 ms002 volumio[846]: info: ControllerMpd::stop Jul 03 23:05:24 ms002 volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Jul 03 23:05:24 ms002 volumio[846]: info: CorePlayQueue::clearPlayQueue Jul 03 23:05:24 ms002 volumio[846]: info: CorePlayQueue::saveQueue Jul 03 23:05:24 ms002 volumio[846]: info: CoreCommandRouter::volumioPushQueue Jul 03 23:05:24 ms002 volumio[846]: info: CoreStateMachine::addQueueItems Jul 03 23:05:24 ms002 volumio[846]: info: CorePlayQueue::addQueueItems Jul 03 23:05:24 ms002 volumio[846]: info: Preload queue cleared Jul 03 23:05:24 ms002 volumio[846]: info: Adding Item to queue: music-library/NAS/Musik/Katzenmusik.zip Jul 03 23:05:24 ms002 volumio[846]: info: Exploding uri music-library/NAS/Musik/Katzenmusik.zip in service mpd Jul 03 23:05:25 ms002 volumio[846]: info: Jul 03 23:05:25 ms002 volumio[846]: ---------------------------- MPD announces state update: player Jul 03 23:05:25 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FKatzenmusik.zip&metadata=false Jul 03 23:05:25 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Katzenmusik.zip Jul 03 23:05:25 ms002 volumio[846]: info: ALBUMART /albumart?cacheid=733&web=petsandmusic/Music%20for%20Cats%20and%20Friends%20Vol.%201/extralarge&path=%2Fmnt%2FNAS%2FMusik%2FKatzenmusik.zip&metadata=false Jul 03 23:05:25 ms002 volumio[846]: info: URI /mnt/NAS/Musik/Katzenmusik.zip Jul 03 23:05:25 ms002 volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 23:05:25 ms002 volumio[846]: Error: Unable to resolve or reject the same promise twice Jul 03 23:05:25 ms002 volumio[846]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 03 23:05:25 ms002 volumio[846]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jul 03 23:05:25 ms002 volumio[846]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 03 23:05:25 ms002 volumio[846]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 03 23:05:25 ms002 volumio[846]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 03 23:05:25 ms002 volumio[846]: at Socket.emit (events.js:315:20) Jul 03 23:05:25 ms002 volumio[846]: at addChunk (internal/streams/readable.js:309:12) Jul 03 23:05:25 ms002 volumio[846]: at readableAddChunk (internal/streams/readable.js:280:11) Jul 03 23:05:25 ms002 volumio[846]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jul 03 23:05:25 ms002 volumio[846]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 03 23:05:25 ms002 volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 23:05:26 ms002 sudo[18287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 23:04 Jul 03 23:05:26 ms002 sudo[18287]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"