-- Logs begin at Wed 2021-12-01 13:09:13 UTC, end at Tue 2024-07-09 13:41:23 UTC. -- Jul 09 13:40:05 opera volumio[1130]: info: Jul 09 13:40:05 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:05 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:05 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:05 opera volumio[1130]: info: sendMpdCommand status took 1 milliseconds Jul 09 13:40:05 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:05 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:05 opera volumio[1130]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 13:40:05 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:05 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:05 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:05 opera volumio[1130]: 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":"Melody of Heartbeat","artist":"Artem Uzunov","album":"Let`S Do It","uri":"http://aqqmusic.tc.qq.com/F000000ab0lM4HISmE.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=8C536C0DD07C766BB0CCDBE8A7762F1595BE537A7AE8D5F7828BBB2653B8AD0B766EF40DED81466A0535922A027E4B44917F8696D414413D&uin=1152921504950983260&src=F000003ZUii01yW1xm.flac&redirect=1&fromtag=111114","trackType":""} Jul 09 13:40:05 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::syncState stateService stop Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus play Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::play index undefined Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:05 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:05 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:05 opera volumio[1130]: info: ------------------------------ 19ms Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:05 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:05 opera volumio[1130]: info: Clearing queue after UPNP request Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::ClearQueue Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::stop Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 13:40:05 opera volumio[1130]: verbose: UNSET VOLATILE Jul 09 13:40:05 opera volumio[1130]: info: CorePlayQueue::clearPlayQueue Jul 09 13:40:05 opera volumio[1130]: info: CorePlayQueue::saveQueue Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::volumioPushQueue Jul 09 13:40:05 opera volumio[1130]: info: Jul 09 13:40:05 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:05 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:05 opera volumio[1130]: error: updateQueue error: null Jul 09 13:40:05 opera volumio[1130]: info: ------------------------------ 2ms Jul 09 13:40:05 opera volumio[1130]: info: Starting UPNP Playback Jul 09 13:40:05 opera volumio[1130]: info: Preparing playback through UPNP Jul 09 13:40:05 opera volumio[1130]: info: CoreCommandRouter::volumioGetState Jul 09 13:40:05 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:05 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 13:40:06 opera volumio[1130]: info: Jul 09 13:40:06 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:06 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:06 opera volumio[1130]: info: Jul 09 13:40:06 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:06 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:06 opera volumio[1130]: info: ------------------------------ 2ms Jul 09 13:40:06 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:06 opera volumio[1130]: info: Jul 09 13:40:06 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:06 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:06 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:06 opera volumio[1130]: info: Jul 09 13:40:06 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:06 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:06 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:06 opera volumio[1130]: info: Jul 09 13:40:06 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:06 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:06 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:06 opera volumio[1130]: info: Jul 09 13:40:06 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:06 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:06 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:07 opera volumio[1130]: info: Jul 09 13:40:07 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:07 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:07 opera volumio[1130]: info: sendMpdCommand status took 1 milliseconds Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:07 opera volumio[1130]: info: Jul 09 13:40:07 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:07 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:07 opera volumio[1130]: info: Jul 09 13:40:07 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:07 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:07 opera volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:07 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:07 opera volumio[1130]: verbose: In UPNP mode Jul 09 13:40:07 opera volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":397,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hurts Like Love","artist":"Costa Mee","album":"Hurts Like Love","uri":"http://aqqmusic.tc.qq.com/M5000013tDJI1fPXKA.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=1AF5CA262A1556DA3E34A9C3E9D79E920047631E61DC19580CD8C560D469771916392F5A7011C5836E44EFF51275E04829C4DA3502D355EB&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Jul 09 13:40:07 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::syncState stateService play Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:07 opera volumio[1130]: info: ------------------------------ 16ms Jul 09 13:40:07 opera volumio[1130]: info: ------------------------------ 12ms Jul 09 13:40:07 opera volumio[1130]: info: sendMpdCommand status took 12 milliseconds Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:07 opera volumio[1130]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 13:40:07 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:07 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:07 opera volumio[1130]: verbose: In UPNP mode Jul 09 13:40:07 opera volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":397,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hurts Like Love","artist":"Costa Mee","album":"Hurts Like Love","uri":"http://aqqmusic.tc.qq.com/M5000013tDJI1fPXKA.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=1AF5CA262A1556DA3E34A9C3E9D79E920047631E61DC19580CD8C560D469771916392F5A7011C5836E44EFF51275E04829C4DA3502D355EB&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Jul 09 13:40:07 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::syncState stateService play Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus play Jul 09 13:40:07 opera volumio[1130]: info: Received an update from plugin. extracting info from payload Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:07 opera volumio[1130]: info: ------------------------------ 39ms Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:07 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:07 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:07 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:07 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:07 opera volumio[1130]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Artem%20Uzunov/Let%60S%20Do%20It/f0fac77e-17c0-4d6b-bfa4-7d0b28aabe5a.jpg' Jul 09 13:40:43 opera volumio[1130]: info: Jul 09 13:40:43 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:43 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:43 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:43 opera volumio[1130]: info: sendMpdCommand status took 1 milliseconds Jul 09 13:40:43 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:43 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:43 opera volumio[1130]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 13:40:43 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:43 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:43 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:43 opera volumio[1130]: 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":"Hurts Like Love","artist":"Costa Mee","album":"Hurts Like Love","uri":"http://aqqmusic.tc.qq.com/M5000013tDJI1fPXKA.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=1AF5CA262A1556DA3E34A9C3E9D79E920047631E61DC19580CD8C560D469771916392F5A7011C5836E44EFF51275E04829C4DA3502D355EB&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Jul 09 13:40:43 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::syncState stateService stop Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus play Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::play index undefined Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:43 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:43 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:43 opera volumio[1130]: info: ------------------------------ 16ms Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:43 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:43 opera volumio[1130]: info: Clearing queue after UPNP request Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::ClearQueue Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::stop Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 13:40:43 opera volumio[1130]: verbose: UNSET VOLATILE Jul 09 13:40:43 opera volumio[1130]: info: CorePlayQueue::clearPlayQueue Jul 09 13:40:43 opera volumio[1130]: info: CorePlayQueue::saveQueue Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::volumioPushQueue Jul 09 13:40:43 opera volumio[1130]: info: Jul 09 13:40:43 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:43 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:43 opera volumio[1130]: error: updateQueue error: null Jul 09 13:40:43 opera volumio[1130]: info: ------------------------------ 4ms Jul 09 13:40:43 opera volumio[1130]: info: Starting UPNP Playback Jul 09 13:40:43 opera volumio[1130]: info: Preparing playback through UPNP Jul 09 13:40:43 opera volumio[1130]: info: CoreCommandRouter::volumioGetState Jul 09 13:40:43 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:43 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:44 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:44 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 4ms Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 2ms Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:44 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:44 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 2ms Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:44 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:44 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:44 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:44 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:44 opera volumio[1130]: info: Jul 09 13:40:44 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:44 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:44 opera volumio[1130]: info: sendMpdCommand status took 3 milliseconds Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 5ms Jul 09 13:40:44 opera volumio[1130]: info: sendMpdCommand status took 4 milliseconds Jul 09 13:40:44 opera volumio[1130]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:44 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:44 opera volumio[1130]: verbose: In UPNP mode Jul 09 13:40:44 opera volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"GOOD-BYE青春","artist":"長渕剛","album":"Tsuyoshi Nagabuchi All Time Best 2014 傷つき打ちのめされても、長渕剛。","uri":"http://aqqmusic.tc.qq.com/M500000z8wyQ4APHlI.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=5CFCA33C649765D9F150162F01C62652EA5443F949C052A71AB45A6C59D743FD08D33D31A47F237FD90FE99CB8D4BDF44524C65B0D059380&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Jul 09 13:40:44 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::syncState stateService play Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 25ms Jul 09 13:40:44 opera volumio[1130]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 09 13:40:44 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:44 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:44 opera volumio[1130]: verbose: In UPNP mode Jul 09 13:40:44 opera volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"GOOD-BYE青春","artist":"長渕剛","album":"Tsuyoshi Nagabuchi All Time Best 2014 傷つき打ちのめされても、長渕剛。","uri":"http://aqqmusic.tc.qq.com/M500000z8wyQ4APHlI.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=5CFCA33C649765D9F150162F01C62652EA5443F949C052A71AB45A6C59D743FD08D33D31A47F237FD90FE99CB8D4BDF44524C65B0D059380&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Jul 09 13:40:44 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::syncState stateService play Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus play Jul 09 13:40:44 opera volumio[1130]: info: Received an update from plugin. extracting info from payload Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:44 opera volumio[1130]: info: ------------------------------ 47ms Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:44 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:44 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:44 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:44 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:49 opera volumio[1130]: info: Jul 09 13:40:49 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:49 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:49 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:49 opera volumio[1130]: info: sendMpdCommand status took 0 milliseconds Jul 09 13:40:49 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:49 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:49 opera volumio[1130]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 13:40:49 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:49 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:49 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:49 opera volumio[1130]: 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":"GOOD-BYE青春","artist":"長渕剛","album":"Tsuyoshi Nagabuchi All Time Best 2014 傷つき打ちのめされても、長渕剛。","uri":"http://aqqmusic.tc.qq.com/M500000z8wyQ4APHlI.mp3?guid=ffffffffb2377c98000001903a3bdd8f&vkey=5CFCA33C649765D9F150162F01C62652EA5443F949C052A71AB45A6C59D743FD08D33D31A47F237FD90FE99CB8D4BDF44524C65B0D059380&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} Jul 09 13:40:49 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::syncState stateService stop Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus play Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::play index undefined Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:49 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:49 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:49 opera volumio[1130]: info: ------------------------------ 14ms Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:49 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:49 opera volumio[1130]: info: Clearing queue after UPNP request Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::ClearQueue Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::stop Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 13:40:49 opera volumio[1130]: verbose: UNSET VOLATILE Jul 09 13:40:49 opera volumio[1130]: info: CorePlayQueue::clearPlayQueue Jul 09 13:40:49 opera volumio[1130]: info: CorePlayQueue::saveQueue Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::volumioPushQueue Jul 09 13:40:49 opera volumio[1130]: info: Jul 09 13:40:49 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:49 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:49 opera volumio[1130]: error: updateQueue error: null Jul 09 13:40:49 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:49 opera volumio[1130]: info: Starting UPNP Playback Jul 09 13:40:49 opera volumio[1130]: info: Preparing playback through UPNP Jul 09 13:40:49 opera volumio[1130]: info: CoreCommandRouter::volumioGetState Jul 09 13:40:49 opera volumio[1130]: info: CorePlayQueue::getTrack 0 Jul 09 13:40:49 opera volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:50 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:50 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:50 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:50 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:50 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 0ms Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:50 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 1ms Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:50 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces state update: player Jul 09 13:40:50 opera volumio[1130]: info: Jul 09 13:40:50 opera volumio[1130]: ---------------------------- MPD announces system playlist update Jul 09 13:40:50 opera volumio[1130]: info: Ignoring MPD Status Update Jul 09 13:40:50 opera volumio[1130]: info: ControllerMpd::getState Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Jul 09 13:40:50 opera volumio[1130]: info: sendMpdCommand status took 1 milliseconds Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:50 opera volumio[1130]: info: sendMpdCommand status took 2 milliseconds Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 3ms Jul 09 13:40:50 opera volumio[1130]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::parseState Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:50 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:50 opera volumio[1130]: verbose: In UPNP mode Jul 09 13:40:50 opera volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"660 Kbps","isStreaming":false,"title":"Eden","artist":"Edelis","album":"Eden","uri":"http://aqqmusic.tc.qq.com/F000001JQRCN3dzOzu.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=F692A324420C7A87559A607B8FF18E7293CD7AA54DC2650AF41A73855F4727B8FBFAB8F41EC682DF5E75D591B8DD42E8E1F5AD6FAD5CBC6B&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Jul 09 13:40:50 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::syncState stateService play Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 11ms Jul 09 13:40:50 opera volumio[1130]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 09 13:40:50 opera volumio[1130]: verbose: ControllerMpd::parseTrackInfo Jul 09 13:40:50 opera volumio[1130]: info: ControllerMpd::pushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::servicePushState Jul 09 13:40:50 opera volumio[1130]: verbose: In UPNP mode Jul 09 13:40:50 opera volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"660 Kbps","isStreaming":false,"title":"Eden","artist":"Edelis","album":"Eden","uri":"http://aqqmusic.tc.qq.com/F000001JQRCN3dzOzu.flac?guid=ffffffffb2377c98000001903a3bdd8f&vkey=F692A324420C7A87559A607B8FF18E7293CD7AA54DC2650AF41A73855F4727B8FBFAB8F41EC682DF5E75D591B8DD42E8E1F5AD6FAD5CBC6B&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} Jul 09 13:40:50 opera volumio[1130]: verbose: CURRENT POSITION 0 Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::syncState stateService play Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::syncState currentStatus play Jul 09 13:40:50 opera volumio[1130]: info: Received an update from plugin. extracting info from payload Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::pushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::volumioPushState Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 13:40:50 opera volumio[1130]: info: ------------------------------ 18ms Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:50 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:50 opera volumio[1130]: info: CoreCommandRouter::volumioGetQueue Jul 09 13:40:50 opera volumio[1130]: info: CoreStateMachine::getQueue Jul 09 13:40:50 opera volumio[1130]: info: CorePlayQueue::getQueue Jul 09 13:40:50 opera volumio[1130]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%95%B7%E6%B8%95%E5%89%9B/Tsuyoshi%20Nagabuchi%20All%20Time%20Best%202014%20%E5%82%B7%E3%81%A4%E3%81%8D%E6%89%93%E3%81%A1%E3%81%AE%E3%82%81%E3%81%95%E3%82%8C%E3%81%A6%E3%82%82%E3%80%81%E9%95%B7%E6%B8%95%E5%89%9B%E3%80%82/b6190f3c-6672-4f9b-8939-4d38d7a5bb03.jpg' Jul 09 13:41:22 opera volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 13:41:22 opera volumio[1130]: { Error: connect ETIMEDOUT 108.160.162.115:443 Jul 09 13:41:22 opera volumio[1130]: at Object._errnoException (util.js:1022:11) Jul 09 13:41:22 opera volumio[1130]: at _exceptionWithHostPort (util.js:1044:20) Jul 09 13:41:22 opera volumio[1130]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 09 13:41:22 opera volumio[1130]: code: 'ETIMEDOUT', Jul 09 13:41:22 opera volumio[1130]: errno: 'ETIMEDOUT', Jul 09 13:41:22 opera volumio[1130]: syscall: 'connect', Jul 09 13:41:22 opera volumio[1130]: address: '108.160.162.115', Jul 09 13:41:22 opera volumio[1130]: port: 443 } Jul 09 13:41:22 opera volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 13:41:23 opera sudo[7045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-09 13:40 Jul 09 13:41:23 opera sudo[7045]: 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="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 16:18:39 CEST 2021" VOLUMIO_VERSION="1.031" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="8579bfbfd528c599f57acb0dd7167775"