-- Logs begin at Wed 2024-07-24 11:30:57 UTC, end at Wed 2024-07-24 11:34:44 UTC. --
Jul 24 11:33:17 volumio volumio[923]: info:
Jul 24 11:33:17 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:17 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:17 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:17 volumio volumio[923]: info: sendMpdCommand status took 4 milliseconds
Jul 24 11:33:17 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:17 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:17 volumio volumio[923]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 24 11:33:17 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:17 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:17 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:17 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:17 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:17 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:17 volumio volumio[923]: info: CorePlayQueue::getTrack 0
Jul 24 11:33:17 volumio volumio[923]: 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":"潇湘雨","artist":"陈影","album":null,"uri":"https://sjy6.stream.qqmusic.qq.com/M500004LkSq13C8kSd.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=DBA6146E66EA649E6C1E7495AE9FA9DF3A0532EC5E0ABAB1A974F817871355613B45ADC4AC3DBD041C0739A73A38B84226A414CB604DF020&uin=22213939&src=M500000G8vBe49FZ92.mp3&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:17 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:17 volumio volumio[923]: info: CoreStateMachine::syncState stateService stop
Jul 24 11:33:17 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play
Jul 24 11:33:17 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:17 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:17 volumio volumio[923]: info: CoreStateMachine::stPlaybackTimer
Jul 24 11:33:17 volumio volumio[923]: info: ------------------------------ 23ms
Jul 24 11:33:26 volumio volumio[923]: info: Clearing queue after UPNP request
Jul 24 11:33:26 volumio volumio[923]: info: CoreStateMachine::ClearQueue
Jul 24 11:33:26 volumio volumio[923]: info: CoreStateMachine::stop
Jul 24 11:33:26 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 11:33:26 volumio volumio[923]: verbose: UNSET VOLATILE
Jul 24 11:33:26 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue
Jul 24 11:33:26 volumio volumio[923]: info: CorePlayQueue::saveQueue
Jul 24 11:33:26 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:26 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:26 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue
Jul 24 11:33:26 volumio volumio[923]: info:
Jul 24 11:33:26 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:26 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:26 volumio volumio[923]: error: updateQueue error: null
Jul 24 11:33:26 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:26 volumio volumio[923]: info: Starting UPNP Playback
Jul 24 11:33:26 volumio volumio[923]: info: Preparing playback through UPNP
Jul 24 11:33:26 volumio volumio[923]: info: CoreCommandRouter::volumioGetState
Jul 24 11:33:26 volumio volumio[923]: info: CorePlayQueue::getTrack 0
Jul 24 11:33:26 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 24 11:33:26 volumio volumio[923]: info:
Jul 24 11:33:26 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:26 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:26 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:26 volumio volumio[923]: info:
Jul 24 11:33:26 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:26 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:26 volumio volumio[923]: info:
Jul 24 11:33:26 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:26 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:26 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:26 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:26 volumio volumio[923]: info:
Jul 24 11:33:26 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:26 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:26 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:26 volumio volumio[923]: info:
Jul 24 11:33:26 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:26 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:26 volumio volumio[923]: info: ------------------------------ 5ms
Jul 24 11:33:26 volumio volumio[923]: info:
Jul 24 11:33:26 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:26 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:26 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:27 volumio volumio[923]: info:
Jul 24 11:33:27 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:27 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:27 volumio volumio[923]: info:
Jul 24 11:33:27 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:27 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:27 volumio volumio[923]: info:
Jul 24 11:33:27 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:27 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:27 volumio volumio[923]: info: sendMpdCommand status took 5 milliseconds
Jul 24 11:33:27 volumio volumio[923]: info: sendMpdCommand status took 2 milliseconds
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:27 volumio volumio[923]: info: ------------------------------ 5ms
Jul 24 11:33:27 volumio volumio[923]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 24 11:33:27 volumio volumio[923]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:27 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:27 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:27 volumio volumio[923]: verbose: In UPNP mode
Jul 24 11:33:27 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"山茶花","artist":"邓丽君","album":"假如我是真的","uri":"https://sjy6.stream.qqmusic.qq.com/M5000049Rk4f1cKHdj.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=1112A0D08C3A039466000E40CB64F21DB8444FE2D0A322E5245DE7541151241A647F9ED8BE8ADA7DD10F514DF9C6282125220328F14D65C1&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:27 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::syncState stateService play
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:27 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:27 volumio volumio[923]: verbose: In UPNP mode
Jul 24 11:33:27 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"山茶花","artist":"邓丽君","album":"假如我是真的","uri":"https://sjy6.stream.qqmusic.qq.com/M5000049Rk4f1cKHdj.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=1112A0D08C3A039466000E40CB64F21DB8444FE2D0A322E5245DE7541151241A647F9ED8BE8ADA7DD10F514DF9C6282125220328F14D65C1&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:27 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::syncState stateService play
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play
Jul 24 11:33:27 volumio volumio[923]: info: Received an update from plugin. extracting info from payload
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:27 volumio volumio[923]: info: ------------------------------ 72ms
Jul 24 11:33:27 volumio volumio[923]: info: ------------------------------ 69ms
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:27 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:27 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:27 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:27 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:27 volumio volumio[923]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E5%BD%B1/2e65b822-a89e-432d-9f19-586183b50e8e.jpg'
Jul 24 11:33:28 volumio volumio[923]: info:
Jul 24 11:33:28 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:28 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:28 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:28 volumio volumio[923]: info: sendMpdCommand status took 2 milliseconds
Jul 24 11:33:28 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:28 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:28 volumio volumio[923]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jul 24 11:33:28 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:28 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:28 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:28 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:28 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:28 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:28 volumio volumio[923]: info: CorePlayQueue::getTrack 0
Jul 24 11:33:28 volumio volumio[923]: 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":"山茶花","artist":"邓丽君","album":"假如我是真的","uri":"https://sjy6.stream.qqmusic.qq.com/M5000049Rk4f1cKHdj.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=1112A0D08C3A039466000E40CB64F21DB8444FE2D0A322E5245DE7541151241A647F9ED8BE8ADA7DD10F514DF9C6282125220328F14D65C1&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:28 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:28 volumio volumio[923]: info: CoreStateMachine::syncState stateService stop
Jul 24 11:33:28 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play
Jul 24 11:33:28 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:28 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:28 volumio volumio[923]: info: CoreStateMachine::stPlaybackTimer
Jul 24 11:33:28 volumio volumio[923]: info: ------------------------------ 32ms
Jul 24 11:33:28 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:28 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:28 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:29 volumio volumio[923]: info: Clearing queue after UPNP request
Jul 24 11:33:29 volumio volumio[923]: info: CoreStateMachine::ClearQueue
Jul 24 11:33:29 volumio volumio[923]: info: CoreStateMachine::stop
Jul 24 11:33:29 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 11:33:29 volumio volumio[923]: verbose: UNSET VOLATILE
Jul 24 11:33:29 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue
Jul 24 11:33:29 volumio volumio[923]: info: CorePlayQueue::saveQueue
Jul 24 11:33:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:29 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:29 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue
Jul 24 11:33:29 volumio volumio[923]: info:
Jul 24 11:33:29 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:29 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:29 volumio volumio[923]: info: Starting UPNP Playback
Jul 24 11:33:29 volumio volumio[923]: info: Preparing playback through UPNP
Jul 24 11:33:29 volumio volumio[923]: info: CoreCommandRouter::volumioGetState
Jul 24 11:33:29 volumio volumio[923]: info: CorePlayQueue::getTrack 0
Jul 24 11:33:29 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 24 11:33:29 volumio volumio[923]: error: updateQueue error: null
Jul 24 11:33:29 volumio volumio[923]: info: ------------------------------ 4ms
Jul 24 11:33:29 volumio volumio[923]: info:
Jul 24 11:33:29 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:29 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:29 volumio volumio[923]: info:
Jul 24 11:33:29 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:29 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:29 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:29 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:29 volumio volumio[923]: info:
Jul 24 11:33:29 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:29 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:29 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:29 volumio volumio[923]: info:
Jul 24 11:33:29 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:29 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:29 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:29 volumio volumio[923]: info:
Jul 24 11:33:29 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:29 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:29 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:29 volumio volumio[923]: info:
Jul 24 11:33:29 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:29 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:29 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:30 volumio volumio[923]: info:
Jul 24 11:33:30 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:30 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:30 volumio volumio[923]: info: sendMpdCommand status took 4 milliseconds
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:30 volumio volumio[923]: info:
Jul 24 11:33:30 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:30 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:30 volumio volumio[923]: info:
Jul 24 11:33:30 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:30 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:30 volumio volumio[923]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 24 11:33:30 volumio volumio[923]: info: sendMpdCommand status took 3 milliseconds
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:30 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:30 volumio volumio[923]: verbose: In UPNP mode
Jul 24 11:33:30 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"想着你亲爱的","artist":"孙露","album":"另一种情感","uri":"https://sjy6.stream.qqmusic.qq.com/M5000032VqZQ38JmMe.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=4F3F2EB1A01A4218A5CC13EE6AE4FA79C7630E7E5F25B9BC2C3B19FB3EB28DEE83AD609AB85067E9C4C88B1889BFE366C04C83988B99CB43&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:30 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::syncState stateService play
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:30 volumio volumio[923]: info: ------------------------------ 23ms
Jul 24 11:33:30 volumio volumio[923]: info: ------------------------------ 16ms
Jul 24 11:33:30 volumio volumio[923]: info: sendMpdCommand playlistinfo took 28 milliseconds
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:30 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:30 volumio volumio[923]: verbose: In UPNP mode
Jul 24 11:33:30 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"想着你亲爱的","artist":"孙露","album":"另一种情感","uri":"https://sjy6.stream.qqmusic.qq.com/M5000032VqZQ38JmMe.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=4F3F2EB1A01A4218A5CC13EE6AE4FA79C7630E7E5F25B9BC2C3B19FB3EB28DEE83AD609AB85067E9C4C88B1889BFE366C04C83988B99CB43&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:30 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::syncState stateService play
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play
Jul 24 11:33:30 volumio volumio[923]: info: Received an update from plugin. extracting info from payload
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:30 volumio volumio[923]: info: ------------------------------ 60ms
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:30 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:30 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:30 volumio volumio[923]: info:
Jul 24 11:33:30 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:30 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:30 volumio volumio[923]: info: sendMpdCommand status took 0 milliseconds
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:30 volumio volumio[923]: info: sendMpdCommand playlistinfo took 0 milliseconds
Jul 24 11:33:30 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:30 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:30 volumio volumio[923]: info: CorePlayQueue::getTrack 0
Jul 24 11:33:30 volumio volumio[923]: 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":"想着你亲爱的","artist":"孙露","album":"另一种情感","uri":"https://sjy6.stream.qqmusic.qq.com/M5000032VqZQ38JmMe.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=4F3F2EB1A01A4218A5CC13EE6AE4FA79C7630E7E5F25B9BC2C3B19FB3EB28DEE83AD609AB85067E9C4C88B1889BFE366C04C83988B99CB43&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:30 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::syncState stateService stop
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::stPlaybackTimer
Jul 24 11:33:30 volumio volumio[923]: info: ------------------------------ 13ms
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:30 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:30 volumio volumio[923]: info: Clearing queue after UPNP request
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::ClearQueue
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::stop
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 24 11:33:30 volumio volumio[923]: verbose: UNSET VOLATILE
Jul 24 11:33:30 volumio volumio[923]: info: CorePlayQueue::clearPlayQueue
Jul 24 11:33:30 volumio volumio[923]: info: CorePlayQueue::saveQueue
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioPushQueue
Jul 24 11:33:30 volumio volumio[923]: info:
Jul 24 11:33:30 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:30 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:30 volumio volumio[923]: info: Starting UPNP Playback
Jul 24 11:33:30 volumio volumio[923]: info: Preparing playback through UPNP
Jul 24 11:33:30 volumio volumio[923]: info: CoreCommandRouter::volumioGetState
Jul 24 11:33:30 volumio volumio[923]: info: CorePlayQueue::getTrack 0
Jul 24 11:33:30 volumio volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 24 11:33:30 volumio volumio[923]: error: updateQueue error: null
Jul 24 11:33:30 volumio volumio[923]: info: ------------------------------ 6ms
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:31 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:31 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:31 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:31 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 1ms
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:31 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:31 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 2ms
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:31 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:31 volumio volumio[923]: info: sendMpdCommand status took 3 milliseconds
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces state update: player
Jul 24 11:33:31 volumio volumio[923]: info: ControllerMpd::getState
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand status
Jul 24 11:33:31 volumio volumio[923]: info:
Jul 24 11:33:31 volumio volumio[923]: ---------------------------- MPD announces system playlist update
Jul 24 11:33:31 volumio volumio[923]: info: Ignoring MPD Status Update
Jul 24 11:33:31 volumio volumio[923]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 24 11:33:31 volumio volumio[923]: info: sendMpdCommand status took 3 milliseconds
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::parseState
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 24 11:33:31 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:31 volumio volumio[923]: verbose: In UPNP mode
Jul 24 11:33:31 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"甘心情愿","artist":"雷婷","album":"今生最爱","uri":"https://sjy6.stream.qqmusic.qq.com/M500000F73lW03Gfvg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=6D7D6382C2AAC788DB2BBC49341D531AF3F23D7D41ACF5DD6432B14BEE2CF273A584E870BDF7458CC9885BFBADB9A4FEED0732C062E4BB4B&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:31 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::syncState stateService play
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus stop
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 27ms
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 19ms
Jul 24 11:33:31 volumio volumio[923]: info: sendMpdCommand playlistinfo took 17 milliseconds
Jul 24 11:33:31 volumio volumio[923]: verbose: ControllerMpd::parseTrackInfo
Jul 24 11:33:31 volumio volumio[923]: info: ControllerMpd::pushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::servicePushState
Jul 24 11:33:31 volumio volumio[923]: verbose: In UPNP mode
Jul 24 11:33:31 volumio volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"甘心情愿","artist":"雷婷","album":"今生最爱","uri":"https://sjy6.stream.qqmusic.qq.com/M500000F73lW03Gfvg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=6D7D6382C2AAC788DB2BBC49341D531AF3F23D7D41ACF5DD6432B14BEE2CF273A584E870BDF7458CC9885BFBADB9A4FEED0732C062E4BB4B&uin=22213939&redirect=1&fromtag=111042","trackType":""}
Jul 24 11:33:31 volumio volumio[923]: verbose: CURRENT POSITION 0
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::syncState stateService play
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::syncState currentStatus play
Jul 24 11:33:31 volumio volumio[923]: info: Received an update from plugin. extracting info from payload
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:31 volumio volumio[923]: info: ------------------------------ 52ms
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:31 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:31 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:31 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:31 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:33:32 volumio volumio[923]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AD%99%E9%9C%B2/%E5%8F%A6%E4%B8%80%E7%A7%8D%E6%83%85%E6%84%9F/edb03955-a7bc-4494-a404-4eb743126b5c.jpg'
Jul 24 11:33:36 volumio volumio[1189]: 35
Jul 24 11:33:36 volumio volumio[1189]: % Total % Received % Xferd Average Speed Time Time Time Current
Jul 24 11:33:36 volumio volumio[1189]: Dload Upload Total Spent Left Speed
Jul 24 11:33:36 volumio volumio[923]: info:
Jul 24 11:33:36 volumio volumio[923]: ---------------------------- Client requests Volume 35
Jul 24 11:33:36 volumio volumio[923]: info: VolumeController::SetAlsaVolume35
Jul 24 11:33:36 volumio volumio[923]: info: CoreStateMachine::pushState
Jul 24 11:33:36 volumio volumio[923]: info: CoreCommandRouter::volumioPushState
Jul 24 11:33:36 volumio volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 24 11:33:36 volumio volumio[1189]: [155B blob data]
Jul 24 11:33:36 volumio volumio[1189]: {"time":1721820816941,"response":"volume Success"}35
Jul 24 11:33:36 volumio volumio[923]: info: CoreCommandRouter::volumioGetQueue
Jul 24 11:33:36 volumio volumio[923]: info: CoreStateMachine::getQueue
Jul 24 11:33:36 volumio volumio[923]: info: CorePlayQueue::getQueue
Jul 24 11:34:44 volumio volumio[923]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 24 11:34:44 volumio volumio[923]: { Error: connect ETIMEDOUT 199.59.148.202:80
Jul 24 11:34:44 volumio volumio[923]: at Object._errnoException (util.js:1022:11)
Jul 24 11:34:44 volumio volumio[923]: at _exceptionWithHostPort (util.js:1044:20)
Jul 24 11:34:44 volumio volumio[923]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
Jul 24 11:34:44 volumio volumio[923]: code: 'ETIMEDOUT',
Jul 24 11:34:44 volumio volumio[923]: errno: 'ETIMEDOUT',
Jul 24 11:34:44 volumio volumio[923]: syscall: 'connect',
Jul 24 11:34:44 volumio volumio[923]: address: '199.59.148.202',
Jul 24 11:34:44 volumio volumio[923]: port: 80 }
Jul 24 11:34:44 volumio volumio[923]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 24 11:34:44 volumio sudo[1881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 11:33
Jul 24 11:34:44 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 8 (jessie)"
NAME="Debian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=debian
HOME_URL="http://www.debian.org/"
SUPPORT_URL="http://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03"
VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae"
VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d"
VOLUMIO_ARCH="x86"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021"
VOLUMIO_VERSION="2.916"
VOLUMIO_HARDWARE="x86"
VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"