-- Logs begin at Thu 2024-04-04 13:31:35 UTC, end at Thu 2024-04-04 13:58:35 UTC. -- Apr 04 13:57:35 volumio volumio[5003]: info: Apr 04 13:57:35 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:57:35 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:57:35 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:57:35 volumio volumio[5003]: info: sendMpdCommand status took 1 milliseconds Apr 04 13:57:35 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:57:35 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:57:35 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 13:57:35 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:57:35 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:57:35 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:35 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:35 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:35 volumio volumio[5003]: info: CorePlayQueue::getTrack 0 Apr 04 13:57:35 volumio volumio[5003]: 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":"http://61.54.94.38/amobile.music.tc.qq.com/M5000027t1oX0Z62vT.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=FFA0998D46FE4EA10436BF1013F8FB8FAF99E8A425642B38771899D1155370759AD5DEDA322FF2439EFD8DB2412D83770913C62AC5B4A48C&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 13:57:35 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::syncState stateService stop Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus play Apr 04 13:57:35 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:35 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::stPlaybackTimer Apr 04 13:57:35 volumio volumio[5003]: info: ------------------------------ 11ms Apr 04 13:57:35 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:57:35 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:57:35 volumio volumio[5003]: info: Clearing queue after UPNP request Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::ClearQueue Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::stop Apr 04 13:57:35 volumio volumio[5003]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 13:57:35 volumio volumio[5003]: verbose: UNSET VOLATILE Apr 04 13:57:35 volumio volumio[5003]: info: CorePlayQueue::clearPlayQueue Apr 04 13:57:35 volumio volumio[5003]: info: CorePlayQueue::saveQueue Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioPushQueue Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: error: updateQueue error: null Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 2ms Apr 04 13:57:36 volumio volumio[5003]: info: Starting UPNP Playback Apr 04 13:57:36 volumio volumio[5003]: info: Preparing playback through UPNP Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioGetState Apr 04 13:57:36 volumio volumio[5003]: info: CorePlayQueue::getTrack 0 Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 2ms Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:57:36 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:57:36 volumio volumio[5003]: info: sendMpdCommand status took 7 milliseconds Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:57:36 volumio volumio[5003]: info: Apr 04 13:57:36 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:36 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:36 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:57:36 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:57:36 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:57:36 volumio volumio[5003]: verbose: In UPNP mode Apr 04 13:57:36 volumio volumio[5003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1964","artist":"Dark","album":"1964 (Radio Edit)","uri":"http://61.54.94.38/amobile.music.tc.qq.com/M500000Kt5oH1MHqVI.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E0C08C9495C6574BFC28A37E1C1099BACC5835571E0AD38DF5CABD91110B10113EF427A5706E0111133AC4AA81B341B79DF2F1E63F3D5836&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 13:57:36 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::syncState stateService play Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus stop Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 22ms Apr 04 13:57:36 volumio volumio[5003]: info: sendMpdCommand status took 19 milliseconds Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 22ms Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:57:36 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 04 13:57:36 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:57:36 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:57:36 volumio volumio[5003]: verbose: In UPNP mode Apr 04 13:57:36 volumio volumio[5003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1964","artist":"Dark","album":"1964 (Radio Edit)","uri":"http://61.54.94.38/amobile.music.tc.qq.com/M500000Kt5oH1MHqVI.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E0C08C9495C6574BFC28A37E1C1099BACC5835571E0AD38DF5CABD91110B10113EF427A5706E0111133AC4AA81B341B79DF2F1E63F3D5836&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 13:57:36 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::syncState stateService play Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus play Apr 04 13:57:36 volumio volumio[5003]: info: Received an update from plugin. extracting info from payload Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:36 volumio volumio[5003]: info: ------------------------------ 62ms Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:57:36 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:57:36 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:57:36 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:57:36 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:57:36 volumio volumio[5003]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BD%95%E8%AE%AD%E7%94%B0/%E6%B3%A2%E7%BD%97%E5%AF%86%E5%A4%9A/827d3383-17f8-41a5-a7d2-fe15505945b2.jpg' Apr 04 13:57:42 volumio volumio[5003]: info: Apr 04 13:57:42 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:57:42 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:57:42 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:57:42 volumio volumio[5003]: info: sendMpdCommand status took 1 milliseconds Apr 04 13:57:42 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:57:42 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:57:42 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 13:57:42 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:57:42 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:42 volumio volumio[5003]: info: CorePlayQueue::getTrack 0 Apr 04 13:57:42 volumio volumio[5003]: 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":"1964","artist":"Dark","album":"1964 (Radio Edit)","uri":"http://61.54.94.38/amobile.music.tc.qq.com/M500000Kt5oH1MHqVI.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E0C08C9495C6574BFC28A37E1C1099BACC5835571E0AD38DF5CABD91110B10113EF427A5706E0111133AC4AA81B341B79DF2F1E63F3D5836&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 13:57:42 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::syncState stateService stop Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus play Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::stPlaybackTimer Apr 04 13:57:42 volumio volumio[5003]: info: ------------------------------ 12ms Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:57:42 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:57:42 volumio volumio[5003]: info: Clearing queue after UPNP request Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::ClearQueue Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::stop Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 13:57:42 volumio volumio[5003]: verbose: UNSET VOLATILE Apr 04 13:57:42 volumio volumio[5003]: info: CorePlayQueue::clearPlayQueue Apr 04 13:57:42 volumio volumio[5003]: info: CorePlayQueue::saveQueue Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::volumioPushQueue Apr 04 13:57:42 volumio volumio[5003]: info: Apr 04 13:57:42 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:42 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:42 volumio volumio[5003]: info: Starting UPNP Playback Apr 04 13:57:42 volumio volumio[5003]: info: Preparing playback through UPNP Apr 04 13:57:42 volumio volumio[5003]: info: CoreCommandRouter::volumioGetState Apr 04 13:57:42 volumio volumio[5003]: info: CorePlayQueue::getTrack 0 Apr 04 13:57:42 volumio volumio[5003]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 13:57:42 volumio volumio[5003]: error: updateQueue error: null Apr 04 13:57:42 volumio volumio[5003]: info: ------------------------------ 5ms Apr 04 13:57:43 volumio volumio[5003]: info: Apr 04 13:57:43 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:43 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:43 volumio volumio[5003]: info: Apr 04 13:57:43 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:43 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:43 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:43 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:43 volumio volumio[5003]: info: Apr 04 13:57:43 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:43 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:43 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:43 volumio volumio[5003]: info: Apr 04 13:57:43 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:43 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:43 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:43 volumio volumio[5003]: info: Apr 04 13:57:43 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:43 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:43 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:43 volumio volumio[5003]: info: Apr 04 13:57:43 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:43 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:43 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:57:44 volumio volumio[5003]: info: Apr 04 13:57:44 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:57:44 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:57:44 volumio volumio[5003]: info: sendMpdCommand status took 4 milliseconds Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:57:44 volumio volumio[5003]: info: Apr 04 13:57:44 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:57:44 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:57:44 volumio volumio[5003]: info: Apr 04 13:57:44 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:57:44 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:57:44 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 04 13:57:44 volumio volumio[5003]: info: sendMpdCommand status took 3 milliseconds Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:57:44 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:57:44 volumio volumio[5003]: verbose: In UPNP mode Apr 04 13:57:44 volumio volumio[5003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":127,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一般音响顶不住","artist":"井井姐","album":"顶级重低音","uri":"http://61.54.94.38/amobile.music.tc.qq.com/M500002Y5kTe2x1ErT.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=8138520E03A2E7812ED9D2267E01405C1424F068CC3448965A335D887C3B42082BA4FFD76711BD7B346E4A51E710651B6CE4E2CE1E4AC913&uin=22213939&src=M5000003x9MZ1E36MW.mp3&redirect=1&fromtag=111042","trackType":""} Apr 04 13:57:44 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::syncState stateService play Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus stop Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:44 volumio volumio[5003]: info: ------------------------------ 34ms Apr 04 13:57:44 volumio volumio[5003]: info: ------------------------------ 23ms Apr 04 13:57:44 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 04 13:57:44 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:57:44 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:57:44 volumio volumio[5003]: verbose: In UPNP mode Apr 04 13:57:44 volumio volumio[5003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":127,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"一般音响顶不住","artist":"井井姐","album":"顶级重低音","uri":"http://61.54.94.38/amobile.music.tc.qq.com/M500002Y5kTe2x1ErT.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=8138520E03A2E7812ED9D2267E01405C1424F068CC3448965A335D887C3B42082BA4FFD76711BD7B346E4A51E710651B6CE4E2CE1E4AC913&uin=22213939&src=M5000003x9MZ1E36MW.mp3&redirect=1&fromtag=111042","trackType":""} Apr 04 13:57:44 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::syncState stateService play Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus play Apr 04 13:57:44 volumio volumio[5003]: info: Received an update from plugin. extracting info from payload Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:57:44 volumio volumio[5003]: info: ------------------------------ 56ms Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:57:44 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:57:44 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:57:44 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:57:44 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:58:06 volumio volumio[5003]: info: Apr 04 13:58:06 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:58:06 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:58:06 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:58:06 volumio volumio[5003]: info: sendMpdCommand status took 2 milliseconds Apr 04 13:58:06 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:58:06 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:58:06 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 04 13:58:06 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:58:06 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:58:06 volumio volumio[5003]: info: CorePlayQueue::getTrack 0 Apr 04 13:58:06 volumio volumio[5003]: 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":"http://61.54.94.38/amobile.music.tc.qq.com/M500002Y5kTe2x1ErT.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=8138520E03A2E7812ED9D2267E01405C1424F068CC3448965A335D887C3B42082BA4FFD76711BD7B346E4A51E710651B6CE4E2CE1E4AC913&uin=22213939&src=M5000003x9MZ1E36MW.mp3&redirect=1&fromtag=111042","trackType":""} Apr 04 13:58:06 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::syncState stateService stop Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus play Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::stPlaybackTimer Apr 04 13:58:06 volumio volumio[5003]: info: ------------------------------ 12ms Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:58:06 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:58:06 volumio volumio[5003]: info: Clearing queue after UPNP request Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::ClearQueue Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::stop Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 13:58:06 volumio volumio[5003]: verbose: UNSET VOLATILE Apr 04 13:58:06 volumio volumio[5003]: info: CorePlayQueue::clearPlayQueue Apr 04 13:58:06 volumio volumio[5003]: info: CorePlayQueue::saveQueue Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::volumioPushQueue Apr 04 13:58:06 volumio volumio[5003]: info: Apr 04 13:58:06 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:06 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:06 volumio volumio[5003]: error: updateQueue error: null Apr 04 13:58:06 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:58:06 volumio volumio[5003]: info: Starting UPNP Playback Apr 04 13:58:06 volumio volumio[5003]: info: Preparing playback through UPNP Apr 04 13:58:06 volumio volumio[5003]: info: CoreCommandRouter::volumioGetState Apr 04 13:58:06 volumio volumio[5003]: info: CorePlayQueue::getTrack 0 Apr 04 13:58:06 volumio volumio[5003]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:07 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 2ms Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:07 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:07 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:07 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:07 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:07 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 1ms Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:58:07 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces state update: player Apr 04 13:58:07 volumio volumio[5003]: info: sendMpdCommand status took 7 milliseconds Apr 04 13:58:07 volumio volumio[5003]: info: ControllerMpd::getState Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand status Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:58:07 volumio volumio[5003]: info: Apr 04 13:58:07 volumio volumio[5003]: ---------------------------- MPD announces system playlist update Apr 04 13:58:07 volumio volumio[5003]: info: Ignoring MPD Status Update Apr 04 13:58:07 volumio volumio[5003]: info: sendMpdCommand status took 5 milliseconds Apr 04 13:58:07 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::parseState Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:58:07 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:58:07 volumio volumio[5003]: verbose: In UPNP mode Apr 04 13:58:07 volumio volumio[5003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"眼泪","artist":"王晰","album":"空","uri":"http://61.54.94.38/amobile.music.tc.qq.com/M5000036RwM10vg5iv.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E580358D3AFA89B0FF50CBE23C10BBD28CCB586D6AAD4FB7B9C456FE7DD900626CFB81893F7AF586CAEDEC3F2519E4C713EB1397DE56FC19&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 13:58:07 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::syncState stateService play Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus stop Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 22ms Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 10ms Apr 04 13:58:07 volumio volumio[5003]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 04 13:58:07 volumio volumio[5003]: verbose: ControllerMpd::parseTrackInfo Apr 04 13:58:07 volumio volumio[5003]: info: ControllerMpd::pushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::servicePushState Apr 04 13:58:07 volumio volumio[5003]: verbose: In UPNP mode Apr 04 13:58:07 volumio volumio[5003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"眼泪","artist":"王晰","album":"空","uri":"http://61.54.94.38/amobile.music.tc.qq.com/M5000036RwM10vg5iv.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E580358D3AFA89B0FF50CBE23C10BBD28CCB586D6AAD4FB7B9C456FE7DD900626CFB81893F7AF586CAEDEC3F2519E4C713EB1397DE56FC19&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 13:58:07 volumio volumio[5003]: verbose: CURRENT POSITION 0 Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::syncState stateService play Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::syncState currentStatus play Apr 04 13:58:07 volumio volumio[5003]: info: Received an update from plugin. extracting info from payload Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::pushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::volumioPushState Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 13:58:07 volumio volumio[5003]: info: ------------------------------ 31ms Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:58:07 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:58:07 volumio volumio[5003]: info: CoreCommandRouter::volumioGetQueue Apr 04 13:58:07 volumio volumio[5003]: info: CoreStateMachine::getQueue Apr 04 13:58:07 volumio volumio[5003]: info: CorePlayQueue::getQueue Apr 04 13:58:35 volumio volumio[5003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 13:58:35 volumio volumio[5003]: { Error: connect ETIMEDOUT 199.16.156.38:443 Apr 04 13:58:35 volumio volumio[5003]: at Object._errnoException (util.js:1022:11) Apr 04 13:58:35 volumio volumio[5003]: at _exceptionWithHostPort (util.js:1044:20) Apr 04 13:58:35 volumio volumio[5003]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 04 13:58:35 volumio volumio[5003]: code: 'ETIMEDOUT', Apr 04 13:58:35 volumio volumio[5003]: errno: 'ETIMEDOUT', Apr 04 13:58:35 volumio volumio[5003]: syscall: 'connect', Apr 04 13:58:35 volumio volumio[5003]: address: '199.16.156.38', Apr 04 13:58:35 volumio volumio[5003]: port: 443 } Apr 04 13:58:35 volumio volumio[5003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 13:58:35 volumio sudo[6028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-04 13:57 Apr 04 13:58:35 volumio sudo[6028]: 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"