-- 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"