-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-05-09 18:20:12 CST. -- May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:19:10 volumio volumio[1369]: info: Discovery: Getting this device information May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState May 09 18:19:10 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:19:10 volumio volumio[1369]: info: Discovery: Getting this device information May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState May 09 18:19:10 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:10 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:19:11 volumio volumio[1369]: verbose: New Socket.io Connection to 192.168.1.43:3000 from 192.168.1.10 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 09 18:19:11 volumio volumio[1369]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.10 UA: Mozilla/5.0 (Linux; Android 15; PHY110 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::volumioGetVisibleSources May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState May 09 18:19:11 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 18:19:11 volumio volumio[1369]: info: Received Get System Info May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:19:11 volumio volumio[1369]: info: Discovery: Getting this device information May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState May 09 18:19:11 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState May 09 18:19:11 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:11 volumio volumio[1369]: info: Listing playlists May 09 18:19:11 volumio volumio[1369]: info: CoreCommandRouter::volumioGetQueue May 09 18:19:11 volumio volumio[1369]: info: CoreStateMachine::getQueue May 09 18:19:11 volumio volumio[1369]: info: CorePlayQueue::getQueue May 09 18:19:34 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:19:34 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:19:34 volumio volumio[1369]: info: Discovery: Getting this device information May 09 18:19:34 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState May 09 18:19:34 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:34 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:19:47 volumio volumio[1369]: info: Listing playlists May 09 18:19:49 volumio volumio[1369]: info: CoreCommandRouter::volumioAddQueueItems May 09 18:19:49 volumio volumio[1369]: info: CoreStateMachine::addQueueItems May 09 18:19:49 volumio volumio[1369]: info: CorePlayQueue::addQueueItems May 09 18:19:49 volumio volumio[1369]: info: Preload queue cleared May 09 18:19:49 volumio volumio[1369]: info: Adding Item to queue: upnp/folder/http://192.168.1.42:8200/ctl/ContentDir@1$14$0 May 09 18:19:49 volumio volumio[1369]: info: Using cached record of: upnp/folder/http://192.168.1.42:8200/ctl/ContentDir@1$14$0 May 09 18:19:49 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue May 09 18:19:49 volumio volumio[1369]: info: CorePlayQueue::saveQueue May 09 18:19:49 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock May 09 18:19:49 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock May 09 18:19:51 volumio volumio[1369]: info: Preload queue cleared May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::ClearQueue May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::stop May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::serviceStop May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 7 May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::serviceStop May 09 18:19:51 volumio volumio[1369]: info: [1746785991246] ControllerUPNPBrowser::stop May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::saveQueue May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::addQueueItems May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::addQueueItems May 09 18:19:51 volumio volumio[1369]: info: Preload queue cleared May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1 May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::saveQueue May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::play index 0 May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::addQueueItems May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::addQueueItems May 09 18:19:51 volumio volumio[1369]: info: Preload queue cleared May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$0 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$0 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$5 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$5 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$6 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$6 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$7 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$7 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$8 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$8 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$9 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$9 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$A May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$A May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$B May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$B May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$C May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$C May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$D May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$D May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$E May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$E May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$F May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$F in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$10 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$10 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$11 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$11 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$12 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$12 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$13 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$13 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$14 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$14 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$15 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$15 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$16 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$16 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$19 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$19 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1A May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1A in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1B May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1B in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1C May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1C in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1D May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1D in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1E May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1E May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1F May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$1F in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$20 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$20 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$21 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$21 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$22 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$22 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$23 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$23 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$24 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$24 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$25 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$25 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$26 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$26 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$27 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$27 May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$28 May 09 18:19:51 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$28 in service upnp_browser May 09 18:19:51 volumio volumio[1369]: info: Adding Item to queue: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$29 May 09 18:19:51 volumio volumio[1369]: info: Using cached record of: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$29 May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::stop May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::play index undefined May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:19:51 volumio volumio[1369]: info: [1746785991269] ControllerUPNPBrowser::clearAddPlayTrack May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand stop took 24 milliseconds May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand stop took 3 milliseconds May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:19:51 volumio volumio[1369]: error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/","s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"701"}],"errorDescription":[{"_":"No such object error"}]}]}]}]}]}} May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:19:51 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand clear took 2 milliseconds May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.42:8200/MediaItems/27.wav" May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:19:51 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 09 18:19:51 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 5ms May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 5ms May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:19:51 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:19:51 volumio volumio[1369]: error: updateQueue error: null May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 10ms May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.42:8200/MediaItems/27.wav" May 09 18:19:51 volumio volumio[1369]: error: updateQueue error: null May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:19:51 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 25ms May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand add "http://192.168.1.42:8200/MediaItems/27.wav" took 1 milliseconds May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:19:51 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 3ms May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand play took 1 milliseconds May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 1ms May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand status took 4 milliseconds May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand status took 4 milliseconds May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:19:51 volumio volumio[1369]: info: May 09 18:19:51 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:19:51 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27.wav","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/27.wav","trackType":"wav"} May 09 18:19:51 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:19:51 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27.wav","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/27.wav","trackType":"wav"} May 09 18:19:51 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:19:51 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 10ms May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 13ms May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand status took 8 milliseconds May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 8 milliseconds May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:19:51 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27.wav","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/27.wav","trackType":"wav"} May 09 18:19:51 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:19:51 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 15ms May 09 18:19:51 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 6 milliseconds May 09 18:19:51 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:19:51 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:19:51 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:19:51 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27.wav","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/27.wav","trackType":"wav"} May 09 18:19:51 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:19:51 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:19:51 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:19:51 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:19:51 volumio volumio[1369]: info: ------------------------------ 19ms May 09 18:20:00 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 18:20:00 volumio volumio[1369]: info: Preload queue cleared May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2B May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2C May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2D May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2E May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2F May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$30 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$31 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$32 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$33 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$34 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$35 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$36 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$37 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$38 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$39 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3A May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3B May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3C May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3D May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3E May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3F May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$40 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$179 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17A May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17B May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17C May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17D May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17E May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17F May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$180 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$181 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$182 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$183 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$184 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$185 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$186 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$187 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$188 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$189 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18A May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18B May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18C May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18D May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18E May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18F May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$190 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$191 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$44 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$45 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$46 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$47 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$48 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$49 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4A May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4B May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4C May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4D May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4E May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4F May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$193 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$194 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$195 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$196 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$197 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$198 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$199 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$19A May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$19B May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$50 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$51 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$52 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$53 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$54 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$55 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$56 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$57 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$58 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$59 May 09 18:20:00 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$5A May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2B in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2C in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2D in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2E in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$2F in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$30 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$31 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$32 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$33 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$34 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$35 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$36 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$37 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$38 in service upnp_browser May 09 18:20:00 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$39 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3A in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3B in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3C in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3D in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3E in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$3F in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$40 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$179 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17A in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17B in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17C in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17D in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17E in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$17F in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$180 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$181 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$182 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$183 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$184 in service upnp_browser May 09 18:20:01 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$185 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$186 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$187 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$188 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$189 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18A in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18B in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18C in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18D in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18E in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$18F in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$190 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$191 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$44 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$45 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$46 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$47 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$48 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$49 in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4A in service upnp_browser May 09 18:20:02 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4B in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4C in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4D in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4E in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$4F in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$193 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$194 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$195 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$196 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$197 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$198 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$199 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$19A in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$19B in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$50 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$51 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$52 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$53 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$54 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$55 in service upnp_browser May 09 18:20:03 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 18:20:03 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$56 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$57 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Preload queue cleared May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$83 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$84 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$85 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$86 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$87 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$88 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$89 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8A May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8B May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8C May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8D May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8E May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8F May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$90 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$91 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$92 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$93 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$94 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$95 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$96 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$97 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$98 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$99 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9A May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9B May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9C May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9D May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9E May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9F May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A0 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A1 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A2 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A3 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A4 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A5 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A6 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A7 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A8 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A9 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$AA May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$AB May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$AC May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$AD May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$AE May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$AF May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$B0 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$B1 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$B2 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$B3 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$B4 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$B5 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$0 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$1 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$2 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$3 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$4 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$5 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$6 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$7 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$B May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$C May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$D May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$E May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$F May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$10 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$11 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$12 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$13 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$14 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$15 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$16 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$17 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$18 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$19 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$1A May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$1B May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$1C May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$1D May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$1E May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$1F May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$20 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$21 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$22 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$23 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$24 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$25 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$26 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$27 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$28 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$29 May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$2A May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$2B May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$2C May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$2D May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$2E May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$2F May 09 18:20:04 volumio volumio[1369]: info: Preloading song: upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$30 May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$83 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$84 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$85 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$86 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$87 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$88 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$89 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8A in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8B in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8C in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8D in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8E in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$8F in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$90 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$91 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$92 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$93 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 18:20:04 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 18:20:04 volumio volumio[1369]: info: Discovery: Getting this device information May 09 18:20:04 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState May 09 18:20:04 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$94 in service upnp_browser May 09 18:20:04 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$95 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$96 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$97 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$98 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$99 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9A in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9B in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9C in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9D in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9E in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$9F in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A0 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A1 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A2 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A3 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A4 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A5 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42$A6 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: Preload queue cleared May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::ClearQueue May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::stop May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::serviceStop May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::serviceStop May 09 18:20:05 volumio volumio[1369]: info: [1746786005884] ControllerUPNPBrowser::stop May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::saveQueue May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::addQueueItems May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::addQueueItems May 09 18:20:05 volumio volumio[1369]: info: Preload queue cleared May 09 18:20:05 volumio volumio[1369]: info: Adding Item to queue: upnp/folder/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42 May 09 18:20:05 volumio volumio[1369]: info: Exploding uri upnp/folder/http://192.168.1.42:8200/ctl/ContentDir@1$14$0$42 in service upnp_browser May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand stop took 8 milliseconds May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:05 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:05 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::saveQueue May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::play index 0 May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::stop May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::play index undefined May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: [1746786005932] ControllerUPNPBrowser::clearAddPlayTrack May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand status took 40 milliseconds May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand status took 40 milliseconds May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand stop took 1 milliseconds May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:20:05 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 6 milliseconds May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 7 milliseconds May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand clear took 7 milliseconds May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.42:8200/MediaItems/5367.mp3" May 09 18:20:05 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: 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":"27.wav","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/27.wav","trackType":"wav"} May 09 18:20:05 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:05 volumio volumio[1369]: info: No code May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:05 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: 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":"27.wav","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/27.wav","trackType":"wav"} May 09 18:20:05 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:05 volumio volumio[1369]: info: No code May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:05 volumio volumio[1369]: info: ------------------------------ 59ms May 09 18:20:05 volumio volumio[1369]: info: ------------------------------ 58ms May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:20:05 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:20:05 volumio volumio[1369]: error: updateQueue error: null May 09 18:20:05 volumio volumio[1369]: info: ------------------------------ 18ms May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.42:8200/MediaItems/5367.mp3" May 09 18:20:05 volumio volumio[1369]: error: updateQueue error: null May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:20:05 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:20:05 volumio volumio[1369]: info: ------------------------------ 24ms May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand add "http://192.168.1.42:8200/MediaItems/5367.mp3" took 0 milliseconds May 09 18:20:05 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:20:05 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:20:05 volumio volumio[1369]: info: ------------------------------ 1ms May 09 18:20:05 volumio volumio[1369]: info: sendMpdCommand play took 1 milliseconds May 09 18:20:05 volumio volumio[1369]: info: ------------------------------ 1ms May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:05 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:05 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:05 volumio volumio[1369]: info: May 09 18:20:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand status took 4 milliseconds May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand status took 3 milliseconds May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:06 volumio volumio[1369]: info: May 09 18:20:06 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:06 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5367.mp3","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/5367.mp3","trackType":"mp3"} May 09 18:20:06 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:06 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5367.mp3","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/5367.mp3","trackType":"mp3"} May 09 18:20:06 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:20:06 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 8ms May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 10ms May 09 18:20:06 volumio volumio[1369]: info: May 09 18:20:06 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:20:06 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:20:06 volumio volumio[1369]: info: May 09 18:20:06 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand status took 5 milliseconds May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 5 milliseconds May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:06 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5367.mp3","artist":null,"album":null,"uri":"http://192.168.1.42:8200/MediaItems/5367.mp3","trackType":"mp3"} May 09 18:20:06 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:20:06 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 12ms May 09 18:20:06 volumio volumio[1369]: info: May 09 18:20:06 volumio volumio[1369]: ---------------------------- MPD announces system playlist update May 09 18:20:06 volumio volumio[1369]: info: Ignoring MPD Status Update May 09 18:20:06 volumio volumio[1369]: info: May 09 18:20:06 volumio volumio[1369]: ---------------------------- MPD announces state update: player May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::getState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 6ms May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand status took 5 milliseconds May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 6 milliseconds May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 2ms May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseState May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:06 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"刚好遇见你 - 冯提莫","artist":"冯提莫","album":"冯提莫","uri":"http://192.168.1.42:8200/MediaItems/5367.mp3","trackType":"mp3"} May 09 18:20:06 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:20:06 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 17ms May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 18:20:06 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:06 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:06 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"刚好遇见你 - 冯提莫","artist":"冯提莫","album":"冯提莫","uri":"http://192.168.1.42:8200/MediaItems/5367.mp3","trackType":"mp3"} May 09 18:20:06 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:20:06 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: ControllerMpd::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::servicePushState May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getTrack 0 May 09 18:20:06 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":202,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"刚好遇见你 - 冯提莫","artist":"冯提莫","album":"冯提莫","uri":"http://192.168.1.42:8200/MediaItems/5367.mp3","trackType":"mp3"} May 09 18:20:06 volumio volumio[1369]: verbose: CURRENT POSITION 0 May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play May 09 18:20:06 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::pushState May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 20ms May 09 18:20:06 volumio volumio[1369]: info: ------------------------------ 15ms May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioGetQueue May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::getQueue May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getQueue May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioGetQueue May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::getQueue May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getQueue May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioGetQueue May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::getQueue May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getQueue May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioGetQueue May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::getQueue May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getQueue May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioGetQueue May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::getQueue May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getQueue May 09 18:20:06 volumio volumio[1369]: info: CoreCommandRouter::volumioGetQueue May 09 18:20:06 volumio volumio[1369]: info: CoreStateMachine::getQueue May 09 18:20:06 volumio volumio[1369]: info: CorePlayQueue::getQueue May 09 18:20:11 volumio volumio[1369]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:20:11 volumio volumio[1369]: Error: connect ECONNREFUSED 67.230.169.182:443 May 09 18:20:11 volumio volumio[1369]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 09 18:20:11 volumio volumio[1369]: errno: -111, May 09 18:20:11 volumio volumio[1369]: code: 'ECONNREFUSED', May 09 18:20:11 volumio volumio[1369]: syscall: 'connect', May 09 18:20:11 volumio volumio[1369]: address: '67.230.169.182', May 09 18:20:11 volumio volumio[1369]: port: 443 May 09 18:20:11 volumio volumio[1369]: } May 09 18:20:11 volumio volumio[1369]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 18:20:12 volumio sudo[5562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 18:19 May 09 18:20:12 volumio sudo[5562]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"