-- Logs begin at Mon 2024-04-08 10:48:46 UTC, end at Mon 2024-04-08 11:00:57 UTC. -- Apr 08 10:59:59 volumio volumio[718]: info: Clearing queue after UPNP request Apr 08 11:00:00 volumio volumio[718]: info: CoreStateMachine::ClearQueue Apr 08 11:00:00 volumio volumio[718]: info: CoreStateMachine::stop Apr 08 11:00:00 volumio volumio[718]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 11:00:00 volumio volumio[718]: info: CorePlayQueue::clearPlayQueue Apr 08 11:00:00 volumio volumio[718]: info: CorePlayQueue::saveQueue Apr 08 11:00:00 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:00 volumio volumio[718]: info: CoreCommandRouter::volumioPushQueue Apr 08 11:00:00 volumio volumio[718]: error: Upnp client error: Error: This socket has been ended by the other party Apr 08 11:00:02 volumio volumio[718]: info: Starting UPNP Playback Apr 08 11:00:02 volumio volumio[718]: info: Preparing playback through UPNP Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::volumioGetState Apr 08 11:00:02 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:02 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 0ms Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:02 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 1ms Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:02 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 1ms Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:02 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 0ms Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:02 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 0ms Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:02 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 1ms Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:02 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:02 volumio volumio[718]: info: Apr 08 11:00:02 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:02 volumio volumio[718]: info: sendMpdCommand status took 1 milliseconds Apr 08 11:00:02 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 11:00:02 volumio volumio[718]: info: sendMpdCommand status took 0 milliseconds Apr 08 11:00:02 volumio volumio[718]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Apr 08 11:00:02 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:02 volumio volumio[718]: verbose: In UPNP mode Apr 08 11:00:02 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"边境-马头琴","artist":"SHAN","album":"风沙","uri":"http://192.168.1.10:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FAndroid%2Fdata%2Fcom.netease.cloudmusic%2Fcache%2FCache%2FMusic1%2F2093088722-256008-3eb23afb2441a34c2aafc9d447f46284.mp3.uc%21&decode=true","trackType":""} Apr 08 11:00:02 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus stop Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 3ms Apr 08 11:00:02 volumio volumio[718]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 11:00:02 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Apr 08 11:00:02 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:02 volumio volumio[718]: verbose: In UPNP mode Apr 08 11:00:02 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"边境-马头琴","artist":"SHAN","album":"风沙","uri":"http://192.168.1.10:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FAndroid%2Fdata%2Fcom.netease.cloudmusic%2Fcache%2FCache%2FMusic1%2F2093088722-256008-3eb23afb2441a34c2aafc9d447f46284.mp3.uc%21&decode=true","trackType":""} Apr 08 11:00:02 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Apr 08 11:00:02 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:02 volumio volumio[718]: info: ------------------------------ 4ms Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:02 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:02 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:02 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:02 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:06 volumio volumio[718]: info: Apr 08 11:00:06 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:06 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:06 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:07 volumio volumio[718]: info: Apr 08 11:00:07 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:07 volumio volumio[718]: info: sendMpdCommand status took 997 milliseconds Apr 08 11:00:07 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:07 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:07 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:07 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 11:00:08 volumio volumio[718]: info: sendMpdCommand status took 250 milliseconds Apr 08 11:00:08 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 11:00:08 volumio volumio[718]: info: Apr 08 11:00:08 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:08 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:08 volumio volumio[718]: info: sendMpdCommand playlistinfo took 251 milliseconds Apr 08 11:00:08 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Apr 08 11:00:08 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:08 volumio volumio[718]: verbose: In UPNP mode Apr 08 11:00:08 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":4,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Take Me To The River","artist":"KALEIDA","album":"Think (Anniversary Edition)","uri":"http://m701.music.126.net/20240408192341/5725c3220f0ee0709ec7f0c8b9205d69/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/20080102653/4b23/9e7e/dc2f/a72e5065324781b4647473ef2ea8b509.m4a","trackType":"m4a"} Apr 08 11:00:08 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Apr 08 11:00:08 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:08 volumio volumio[718]: info: ------------------------------ 1253ms Apr 08 11:00:08 volumio volumio[718]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 08 11:00:08 volumio volumio[718]: info: sendMpdCommand status took 5 milliseconds Apr 08 11:00:08 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Apr 08 11:00:08 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:08 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:08 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Apr 08 11:00:08 volumio volumio[718]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take Me To The River","artist":"KALEIDA","album":"Think (Anniversary Edition)","uri":"http://m701.music.126.net/20240408192341/5725c3220f0ee0709ec7f0c8b9205d69/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/20080102653/4b23/9e7e/dc2f/a72e5065324781b4647473ef2ea8b509.m4a","trackType":"m4a"} Apr 08 11:00:08 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::syncState stateService stop Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::stPlaybackTimer Apr 08 11:00:08 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:08 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Apr 08 11:00:08 volumio volumio[718]: 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} Apr 08 11:00:08 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::syncState stateService stop Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus stop Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:08 volumio volumio[718]: info: No code Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:08 volumio volumio[718]: info: ------------------------------ 266ms Apr 08 11:00:08 volumio volumio[718]: info: ------------------------------ 16ms Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:08 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:08 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:08 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:08 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:08 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:08 volumio volumio[718]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/KALEIDA/Think%20(Anniversary%20Edition)/85cd2f45-9004-4b60-bb85-825130397455.jpg' Apr 08 11:00:35 volumio volumio[718]: info: Clearing queue after UPNP request Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::ClearQueue Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::stop Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 11:00:36 volumio volumio[718]: info: CorePlayQueue::clearPlayQueue Apr 08 11:00:36 volumio volumio[718]: info: CorePlayQueue::saveQueue Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::volumioPushQueue Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:36 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:36 volumio volumio[718]: error: updateQueue error: null Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 1ms Apr 08 11:00:36 volumio volumio[718]: info: Starting UPNP Playback Apr 08 11:00:36 volumio volumio[718]: info: Preparing playback through UPNP Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::volumioGetState Apr 08 11:00:36 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:36 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 0ms Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:36 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 0ms Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:36 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 1ms Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:36 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 1ms Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:36 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 0ms Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces system playlist update Apr 08 11:00:36 volumio volumio[718]: info: Ignoring MPD Status Update Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 1ms Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:36 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:36 volumio volumio[718]: info: Apr 08 11:00:36 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:36 volumio volumio[718]: info: sendMpdCommand status took 1 milliseconds Apr 08 11:00:36 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 11:00:36 volumio volumio[718]: info: sendMpdCommand status took 0 milliseconds Apr 08 11:00:36 volumio volumio[718]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Apr 08 11:00:36 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:36 volumio volumio[718]: verbose: In UPNP mode Apr 08 11:00:36 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":385,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On…?","uri":"http://m701.music.126.net/20240408192535/a5d79f419f79a3013744220c2f70677d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32385807852/3f0d/b63b/509e/7d603641d3ef6343a49a0333c798fc12.m4a","trackType":"m4a"} Apr 08 11:00:36 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus stop Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 3ms Apr 08 11:00:36 volumio volumio[718]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 11:00:36 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Apr 08 11:00:36 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:36 volumio volumio[718]: verbose: In UPNP mode Apr 08 11:00:36 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":385,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On…?","uri":"http://m701.music.126.net/20240408192535/a5d79f419f79a3013744220c2f70677d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/32385807852/3f0d/b63b/509e/7d603641d3ef6343a49a0333c798fc12.m4a","trackType":"m4a"} Apr 08 11:00:36 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Apr 08 11:00:36 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:36 volumio volumio[718]: info: ------------------------------ 5ms Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:36 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:36 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:36 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:36 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:37 volumio volumio[718]: info: Apr 08 11:00:37 volumio volumio[718]: ---------------------------- MPD announces state update: player Apr 08 11:00:37 volumio volumio[718]: info: ControllerMpd::getState Apr 08 11:00:37 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Apr 08 11:00:37 volumio volumio[718]: info: sendMpdCommand status took 1 milliseconds Apr 08 11:00:37 volumio volumio[718]: verbose: ControllerMpd::parseState Apr 08 11:00:37 volumio volumio[718]: info: ControllerMpd::pushState Apr 08 11:00:37 volumio volumio[718]: info: CoreCommandRouter::servicePushState Apr 08 11:00:37 volumio volumio[718]: info: CoreStateMachine::pushState Apr 08 11:00:37 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:37 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Apr 08 11:00:37 volumio volumio[718]: 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} Apr 08 11:00:37 volumio volumio[718]: verbose: CURRENT POSITION 0 Apr 08 11:00:37 volumio volumio[718]: info: CoreStateMachine::syncState stateService stop Apr 08 11:00:37 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Apr 08 11:00:37 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Apr 08 11:00:37 volumio volumio[718]: info: CoreStateMachine::stPlaybackTimer Apr 08 11:00:37 volumio volumio[718]: info: ------------------------------ 5ms Apr 08 11:00:37 volumio volumio[718]: info: CoreCommandRouter::volumioGetQueue Apr 08 11:00:37 volumio volumio[718]: info: CoreStateMachine::getQueue Apr 08 11:00:37 volumio volumio[718]: info: CorePlayQueue::getQueue Apr 08 11:00:56 volumio volumio[718]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 11:00:56 volumio volumio[718]: Error: connect ETIMEDOUT 162.125.32.6:443 Apr 08 11:00:56 volumio volumio[718]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 08 11:00:56 volumio volumio[718]: errno: -110, Apr 08 11:00:56 volumio volumio[718]: code: 'ETIMEDOUT', Apr 08 11:00:56 volumio volumio[718]: syscall: 'connect', Apr 08 11:00:56 volumio volumio[718]: address: '162.125.32.6', Apr 08 11:00:56 volumio volumio[718]: port: 443 Apr 08 11:00:56 volumio volumio[718]: } Apr 08 11:00:56 volumio volumio[718]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 11:00:57 volumio sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 10:59 Apr 08 11:00:57 volumio sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"