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