-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-07-18 23:39:02 CST. -- Jul 18 23:38:09 volumio volumio[1064]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%93%9D%E5%BF%83%E7%BE%BD/%E9%98%BF%E6%8B%89%E6%96%AF%E5%8A%A0%E6%B5%B7%E6%B9%BE/4717db99-2da8-427f-82da-8b2d5d2ff004.jpg' Jul 18 23:38:09 volumio volumio[1064]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E5%85%86%E5%AE%87/%E5%86%AC%E7%9C%A0%C2%B72023/cee817e3-1407-4cef-a6ed-3f2bea4d41b2.jpg' Jul 18 23:38:09 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 4 Jul 18 23:38:09 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Jul 18 23:38:09 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 18 23:38:09 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 18 23:38:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 18 23:38:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 18 23:38:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 18 23:38:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 18 23:38:10 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Jul 18 23:38:10 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 18 23:38:10 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 18 23:38:10 volumio volumio[1064]: info: Listing playlists Jul 18 23:38:10 volumio volumio[1064]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 23:38:10 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 23:38:10 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:10 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:10 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 23:38:11 volumio volumio[1064]: info: Discovery: Getting this device information Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 23:38:11 volumio volumio[1064]: info: Discovery: Getting this device information Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 18 23:38:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 23:38:27 volumio volumio[1064]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 18 23:38:27 volumio volumio[1064]: info: Retrying Login Due to Network Error Jul 18 23:38:32 volumio volumio[1064]: info: Preload queue cleared Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::ClearQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::stop Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::stPlaybackTimer Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::serviceStop Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::serviceStop Jul 18 23:38:32 volumio volumio[1064]: info: [1752853112053] ControllerUPNPBrowser::stop Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::clearPlayQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::saveQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::addQueueItems Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::addQueueItems Jul 18 23:38:32 volumio volumio[1064]: info: Preload queue cleared Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1B Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1B Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$38 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$38 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$39 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$39 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$31 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$31 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$45 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$45 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$37 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$37 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$24 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$24 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2B Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2B Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$25 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$25 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$46 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$46 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$42 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$42 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4E Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4E Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$17 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$17 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2E Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2E Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$43 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$43 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1F Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1F Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$50 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$50 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$21 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$21 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$9 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$9 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2C Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2C Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$36 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$36 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3B Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3B Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$44 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$44 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4F Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4F Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$55 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$55 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$30 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$30 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$35 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$35 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$33 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$33 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3A Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3A Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$6 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$6 Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::saveQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPlay Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::play index 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::addQueueItems Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::addQueueItems Jul 18 23:38:32 volumio volumio[1064]: info: Preload queue cleared Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3C Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3C Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3D Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3D Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3E Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$3E Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$54 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$54 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$40 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$40 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$41 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$41 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$32 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$32 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2A Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2A Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$53 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$53 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1D Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1D Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$34 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$34 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$F Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$F Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$16 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$16 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$13 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$13 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$12 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$12 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$11 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$11 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2F Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2F Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4C Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4C Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$18 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$18 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2D Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$2D Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$29 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$29 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$28 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$28 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$27 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$27 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1A Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1A Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$23 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$23 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$22 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$22 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$D Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$D Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$10 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$10 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$56 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$56 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$14 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$14 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$52 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$52 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$8 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$8 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$7 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$7 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4B Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4B Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1C Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$1C Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$15 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$15 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$51 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$51 Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$C Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$C Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4D Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$4D Jul 18 23:38:32 volumio volumio[1064]: info: Adding Item to queue: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$57 Jul 18 23:38:32 volumio volumio[1064]: info: Using cached record of: upnp/http://192.168.0.108:8200/ctl/ContentDir@1$4$57 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::stop Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::saveQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::play index undefined Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::startPlaybackTimer Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: info: [1752853112102] ControllerUPNPBrowser::clearAddPlayTrack Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces state update: player Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand stop took 55 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::getState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand stop took 7 milliseconds Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Jul 18 23:38:32 volumio volumio[1064]: info: Ignoring MPD Status Update Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand status took 2 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand clear took 1 milliseconds Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.108:8200/MediaItems/46.flac" Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces state update: player Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::getState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces state update: player Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::getState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Jul 18 23:38:32 volumio volumio[1064]: error: updateQueue error: null Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 5ms Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Jul 18 23:38:32 volumio volumio[1064]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 8ms Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.108:8200/MediaItems/46.flac" Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Jul 18 23:38:32 volumio volumio[1064]: info: Ignoring MPD Status Update Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand status took 40 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand status took 40 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand add "http://192.168.0.108:8200/MediaItems/46.flac" took 2 milliseconds Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand play Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 23:38:32 volumio volumio[1064]: verbose: CURRENT POSITION 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: No code Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 23:38:32 volumio volumio[1064]: verbose: CURRENT POSITION 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: No code Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 86ms Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 86ms Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Jul 18 23:38:32 volumio volumio[1064]: info: Ignoring MPD Status Update Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Jul 18 23:38:32 volumio volumio[1064]: info: Ignoring MPD Status Update Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces state update: player Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Jul 18 23:38:32 volumio volumio[1064]: info: Ignoring MPD Status Update Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces state update: player Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 51ms Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand play took 49 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::getState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 4ms Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::getState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 4ms Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Jul 18 23:38:32 volumio volumio[1064]: info: Ignoring MPD Status Update Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces state update: player Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::getState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Jul 18 23:38:32 volumio volumio[1064]: info: Ignoring MPD Status Update Jul 18 23:38:32 volumio volumio[1064]: info: Jul 18 23:38:32 volumio volumio[1064]: ---------------------------- MPD announces state update: player Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::getState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand status took 34 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 36ms Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand status took 34 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 33ms Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand status took 32 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 31ms Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand status took 31 milliseconds Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 18 23:38:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Jul 18 23:38:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":290,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"打上花火","artist":"初音ミク","album":"打上花火","uri":"http://192.168.0.108:8200/MediaItems/46.flac","trackType":"flac"} Jul 18 23:38:32 volumio volumio[1064]: verbose: CURRENT POSITION 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":290,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"打上花火","artist":"初音ミク","album":"打上花火","uri":"http://192.168.0.108:8200/MediaItems/46.flac","trackType":"flac"} Jul 18 23:38:32 volumio volumio[1064]: verbose: CURRENT POSITION 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Jul 18 23:38:32 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":290,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"打上花火","artist":"初音ミク","album":"打上花火","uri":"http://192.168.0.108:8200/MediaItems/46.flac","trackType":"flac"} Jul 18 23:38:32 volumio volumio[1064]: verbose: CURRENT POSITION 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Jul 18 23:38:32 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: ControllerMpd::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 30 Jul 18 23:38:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":290,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"打上花火","artist":"初音ミク","album":"打上花火","uri":"http://192.168.0.108:8200/MediaItems/46.flac","trackType":"flac"} Jul 18 23:38:32 volumio volumio[1064]: verbose: CURRENT POSITION 30 Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Jul 18 23:38:32 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 56ms Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 78ms Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 76ms Jul 18 23:38:32 volumio volumio[1064]: info: ------------------------------ 74ms Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Jul 18 23:38:32 volumio volumio[1064]: info: CoreStateMachine::getQueue Jul 18 23:38:32 volumio volumio[1064]: info: CorePlayQueue::getQueue Jul 18 23:38:37 volumio volumio[1064]: info: MyVolumio login type: Token Jul 18 23:39:01 volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 23:39:01 volumio volumio[1064]: Error: connect ETIMEDOUT 128.121.243.235:443 Jul 18 23:39:01 volumio volumio[1064]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 18 23:39:01 volumio volumio[1064]: errno: -110, Jul 18 23:39:01 volumio volumio[1064]: code: 'ETIMEDOUT', Jul 18 23:39:01 volumio volumio[1064]: syscall: 'connect', Jul 18 23:39:01 volumio volumio[1064]: address: '128.121.243.235', Jul 18 23:39:01 volumio volumio[1064]: port: 443 Jul 18 23:39:01 volumio volumio[1064]: } Jul 18 23:39:01 volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 23:39:02 volumio sudo[2199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 23:38 Jul 18 23:39:02 volumio sudo[2199]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"