-- Logs begin at Sat 2025-06-21 10:35:49 +04, end at Sat 2025-06-21 11:11:52 +04. -- Jun 21 11:10:03 pecanpiplus ntpd[790]: Soliciting pool server 2a02:2a50:6::123 Jun 21 11:10:11 pecanpiplus volumio[832]: info: Touch event received, exiting sleep mode Jun 21 11:10:11 pecanpiplus volumio[832]: ACTIVITY STATUS CHANGED TO: 1 Jun 21 11:10:11 pecanpiplus volumio[832]: info: Touch event received, exiting sleep mode Jun 21 11:10:11 pecanpiplus volumio[832]: ACTIVITY STATUS CHANGED TO: 1 Jun 21 11:10:11 pecanpiplus volumio[832]: info: Touch event received, exiting sleep mode Jun 21 11:10:11 pecanpiplus volumio[832]: ACTIVITY STATUS CHANGED TO: 1 Jun 21 11:10:11 pecanpiplus sudo[6850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jun 21 11:10:11 pecanpiplus sudo[6850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 11:10:11 pecanpiplus sudo[6850]: pam_unix(sudo:session): session closed for user root Jun 21 11:10:11 pecanpiplus sudo[6853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jun 21 11:10:11 pecanpiplus sudo[6853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 11:10:11 pecanpiplus sudo[6853]: pam_unix(sudo:session): session closed for user root Jun 21 11:10:15 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:10:16 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:17 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:10:18 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:19 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 11:10:19 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 11:10:19 pecanpiplus volumio[832]: info: Discovery: Getting this device information Jun 21 11:10:19 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioGetState Jun 21 11:10:19 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:19 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 11:10:19 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 Jun 21 11:10:19 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 in service upnp_browser Jun 21 11:10:19 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 in service upnp_browser Jun 21 11:10:19 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 in service upnp_browser Jun 21 11:10:19 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 in service upnp_browser Jun 21 11:10:19 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:20 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:10:20 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:20 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:20 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:20 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385 Jun 21 11:10:20 pecanpiplus volumio[832]: info: Exploding uri upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385 in service upnp_browser Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:20 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:20 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:20 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:20 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:10:20 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:20 pecanpiplus volumio[832]: info: [1750489820783] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:10:20 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:10:29 pecanpiplus volumio[832]: {"status":"stop","position":1,"title":"Pink Floyd - B","artist":"","album":"Pink Floyd - A Foot In The Door. 2018. EU","albumart":"/albumart?cacheid=750&web=/Pink%20Floyd%20-%20A%20Foot%20In%20The%20Door.%202018.%20EU/extralarge&path=&metadata=false","uri":"http://192.168.68.96:50002/m/NDLNA/4208.flac","seek":250,"duration":1414,"random":true,"repeat":true,"repeatSingle":false,"consume":false,"volume":55,"dbVolume":null,"disableVolumeControl":false,"mute":false,"volatile":false,"service":"upnp_browser"} Jun 21 11:10:29 pecanpiplus volumio[832]: ACTIVITY STATUS CHANGED TO: 0 Jun 21 11:10:41 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:41 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:10:41 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:41 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:41 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:41 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385 Jun 21 11:10:41 pecanpiplus volumio[832]: info: Using cached record of: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385 Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:41 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:41 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:41 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:41 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:10:41 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:41 pecanpiplus volumio[832]: info: [1750489841734] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:10:41 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:10:44 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:10:44 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:10:44 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:44 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:44 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:10:44 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:44 pecanpiplus volumio[832]: info: [1750489844021] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:10:44 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:10:47 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:47 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:47 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 Jun 21 11:10:47 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:47 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:47 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:48 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3198 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2389 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3197 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2391 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3196 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2385 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3195 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2393 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3194 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2388 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3193 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2386 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3192 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2390 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3191 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2392 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 Jun 21 11:10:48 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@3190 Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:48 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:48 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:49 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 11:10:49 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 11:10:49 pecanpiplus volumio[832]: info: Discovery: Getting this device information Jun 21 11:10:49 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioGetState Jun 21 11:10:49 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:49 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 11:10:50 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:50 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:10:50 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:10:50 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:10:50 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:10:50 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:50 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$2385$2385$@2387 Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:10:50 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:10:50 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:10:50 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:10:50 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:56 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 11:10:56 pecanpiplus volumio[832]: info: Received Get System Info Jun 21 11:10:56 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 11:10:56 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 11:10:56 pecanpiplus volumio[832]: info: Discovery: Getting this device information Jun 21 11:10:56 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioGetState Jun 21 11:10:56 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:10:56 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 11:10:59 pecanpiplus volumio[832]: {"status":"stop","position":1,"title":"Pink Floyd - B","artist":"","album":"Pink Floyd - A Foot In The Door. 2018. EU","albumart":"/albumart?cacheid=750&web=/Pink%20Floyd%20-%20A%20Foot%20In%20The%20Door.%202018.%20EU/extralarge&path=&metadata=false","uri":"http://192.168.68.96:50002/m/NDLNA/4208.flac","seek":250,"duration":1414,"random":true,"repeat":true,"repeatSingle":false,"consume":false,"volume":55,"dbVolume":null,"disableVolumeControl":false,"mute":false,"volatile":false,"service":"upnp_browser"} Jun 21 11:10:59 pecanpiplus volumio[832]: ACTIVITY STATUS CHANGED TO: 0 Jun 21 11:11:03 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:11:03 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:05 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:11:05 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:05 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:05 pecanpiplus volumio[832]: info: Preloading song: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:05 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 in service upnp_browser Jun 21 11:11:05 pecanpiplus volumio[832]: info: Exploding uri upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 in service upnp_browser Jun 21 11:11:07 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:07 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:07 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:07 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:07 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:07 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:07 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:07 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:07 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:08 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:08 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:08 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:08 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:08 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:08 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:08 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:08 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:08 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:08 pecanpiplus ntpd[790]: Soliciting pool server 2a00:f38:b::123 Jun 21 11:11:09 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:09 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:09 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:09 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:09 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:09 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:09 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:09 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:09 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:09 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:09 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:09 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:09 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:10 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:10 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:10 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:10 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:10 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:10 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:10 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:10 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:10 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:10 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:10 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:10 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:10 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3618 Jun 21 11:11:10 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:11 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:11 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:11 pecanpiplus volumio[832]: info: Using cached record of: upnp/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618$@3635 Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:11 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:11 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:11 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618 Jun 21 11:11:11 pecanpiplus volumio[832]: info: Exploding uri upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618 in service upnp_browser Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:11 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:11:11 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:11 pecanpiplus volumio[832]: info: [1750489871750] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:11:11 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:11:12 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:12 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:12 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:12 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:12 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:12 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618 Jun 21 11:11:12 pecanpiplus volumio[832]: info: Using cached record of: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618 Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:12 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:12 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:12 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:12 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:11:12 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:12 pecanpiplus volumio[832]: info: [1750489872466] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:11:12 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:11:13 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::ClearQueue Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:13 pecanpiplus volumio[832]: info: CorePlayQueue::clearPlayQueue Jun 21 11:11:13 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::addQueueItems Jun 21 11:11:13 pecanpiplus volumio[832]: info: CorePlayQueue::addQueueItems Jun 21 11:11:13 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:13 pecanpiplus volumio[832]: info: Adding Item to queue: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618 Jun 21 11:11:13 pecanpiplus volumio[832]: info: Using cached record of: upnp/folder/http://192.168.68.96:50001/ContentDirectory/control@29$3618$3618 Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPushQueue Jun 21 11:11:13 pecanpiplus volumio[832]: info: CorePlayQueue::saveQueue Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::updateTrackBlock Jun 21 11:11:13 pecanpiplus volumio[832]: info: CorePlayQueue::getTrackBlock Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::play index 0 Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::stop Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:13 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:13 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:11:13 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:13 pecanpiplus volumio[832]: info: [1750489873116] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:11:13 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:11:14 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 11:11:14 pecanpiplus volumio[832]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 21 11:11:19 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 11:11:19 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 11:11:19 pecanpiplus volumio[832]: info: Discovery: Getting this device information Jun 21 11:11:19 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioGetState Jun 21 11:11:19 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:19 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 11:11:20 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:20 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:20 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:20 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:20 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:11:20 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:20 pecanpiplus volumio[832]: info: [1750489880293] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:11:20 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:11:24 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioPlay Jun 21 11:11:24 pecanpiplus volumio[832]: info: CoreStateMachine::play index undefined Jun 21 11:11:24 pecanpiplus volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 11:11:24 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:24 pecanpiplus volumio[832]: info: CoreStateMachine::startPlaybackTimer Jun 21 11:11:24 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:24 pecanpiplus volumio[832]: info: [1750489884812] ControllerUPNPBrowser::clearAddPlayTrack Jun 21 11:11:24 pecanpiplus volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 11:11:29 pecanpiplus volumio[832]: {"status":"stop","position":1,"title":"Pink Floyd - B","artist":"","album":"Pink Floyd - A Foot In The Door. 2018. EU","albumart":"/albumart?cacheid=750&web=/Pink%20Floyd%20-%20A%20Foot%20In%20The%20Door.%202018.%20EU/extralarge&path=&metadata=false","uri":"http://192.168.68.96:50002/m/NDLNA/4208.flac","seek":250,"duration":1414,"random":true,"repeat":true,"repeatSingle":false,"consume":false,"volume":55,"dbVolume":null,"disableVolumeControl":false,"mute":false,"volatile":false,"service":"upnp_browser"} Jun 21 11:11:29 pecanpiplus volumio[832]: ACTIVITY STATUS CHANGED TO: 0 Jun 21 11:11:36 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 21 11:11:36 pecanpiplus volumio[832]: info: CURURI: albums:// Jun 21 11:11:36 pecanpiplus volumio[832]: error: MPD error: The expression evaluated to a falsy value: Jun 21 11:11:36 pecanpiplus volumio[832]: assert.ok(self.idling) Jun 21 11:11:36 pecanpiplus volumio[832]: error: The expression evaluated to a falsy value: Jun 21 11:11:36 pecanpiplus volumio[832]: assert.ok(self.idling) Jun 21 11:11:46 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:11:46 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:48 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:11:48 pecanpiplus volumio[832]: info: Preload queue cleared Jun 21 11:11:49 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 11:11:49 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 11:11:49 pecanpiplus volumio[832]: info: Discovery: Getting this device information Jun 21 11:11:49 pecanpiplus volumio[832]: info: CoreCommandRouter::volumioGetState Jun 21 11:11:49 pecanpiplus volumio[832]: info: CorePlayQueue::getTrack 0 Jun 21 11:11:49 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 11:11:50 pecanpiplus volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 21 11:11:50 pecanpiplus volumio[832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 11:11:50 pecanpiplus volumio[832]: TypeError: Cannot read property 'length' of undefined Jun 21 11:11:50 pecanpiplus volumio[832]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 21 11:11:50 pecanpiplus volumio[832]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 21 11:11:50 pecanpiplus volumio[832]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 21 11:11:50 pecanpiplus volumio[832]: at Parser.emit (events.js:315:20) Jun 21 11:11:50 pecanpiplus volumio[832]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 21 11:11:50 pecanpiplus volumio[832]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 21 11:11:50 pecanpiplus volumio[832]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 21 11:11:50 pecanpiplus volumio[832]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 21 11:11:50 pecanpiplus volumio[832]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 21 11:11:50 pecanpiplus volumio[832]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 21 11:11:50 pecanpiplus volumio[832]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 21 11:11:50 pecanpiplus volumio[832]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 21 11:11:50 pecanpiplus volumio[832]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 21 11:11:50 pecanpiplus volumio[832]: at IncomingMessage.emit (events.js:327:22) Jun 21 11:11:50 pecanpiplus volumio[832]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 21 11:11:50 pecanpiplus volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 21 11:11:50 pecanpiplus volumio[832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 11:11:52 pecanpiplus sudo[7109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-21 11:10 Jun 21 11:11:52 pecanpiplus sudo[7109]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="orchard" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Mar 2025 06:55:38 PM CET" VOLUMIO_VERSION="3.793" VOLUMIO_HARDWARE="pi-kiosk" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Orchard Audio PecanPi+ Streamer Premium" VOLUMIO_VENDOR="Orchard Audio" VOLUMIO_MODEL="PecanPi+ Streamer Premium" VOLUMIO_HASH="51351bccb1b7851be50d1946377b8590"