-- Logs begin at Sat 2024-07-06 01:54:46 UTC, end at Sat 2024-07-06 02:03:50 UTC. -- Jul 06 02:02:09 volumio dhcpcd[1058]: wlan0: offered 192.168.0.241 from 192.168.0.3 Jul 06 02:02:09 volumio dhcpcd[1058]: wlan0: leased 192.168.0.241 for 42000 seconds Jul 06 02:02:09 volumio avahi-daemon[669]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.241. Jul 06 02:02:09 volumio dhcpcd[1058]: eth0: adding route to 192.168.0.0/24 Jul 06 02:02:09 volumio dhcpcd[1058]: eth0: adding default route via 192.168.0.3 Jul 06 02:02:09 volumio dhcpcd[1058]: wlan0: adding host route to 192.168.0.241 via 127.0.0.1 Jul 06 02:02:09 volumio dhcpcd[1058]: wlan0: adding route to 192.168.0.0/24 Jul 06 02:02:09 volumio dhcpcd[1058]: wlan0: adding default route via 192.168.0.3 Jul 06 02:02:09 volumio avahi-daemon[669]: New relevant interface wlan0.IPv4 for mDNS. Jul 06 02:02:09 volumio avahi-daemon[669]: Registering new address record for 192.168.0.241 on wlan0.IPv4. Jul 06 02:02:09 volumio dhcpcd[1058]: eth0: removing route to 192.168.0.0/24 Jul 06 02:02:09 volumio dhcpcd[1058]: eth0: removing default route via 192.168.0.3 Jul 06 02:02:10 volumio ntpd[728]: Listen normally on 4 wlan0 192.168.0.241 UDP 123 Jul 06 02:02:10 volumio ntpd[728]: peers refreshed Jul 06 02:02:24 volumio volumio[1225]: Discovery: this is already registered, b65e407a-a59b-46c1-90b3-d241e744b38f Jul 06 02:02:24 volumio volumio[1225]: info: mDNS: Found device Volumio Jul 06 02:02:24 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Jul 06 02:02:43 volumio volumio[1225]: info: Clearing queue after UPNP request Jul 06 02:02:43 volumio volumio[1225]: info: CoreStateMachine::ClearQueue Jul 06 02:02:43 volumio volumio[1225]: info: CoreStateMachine::stop Jul 06 02:02:43 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 02:02:43 volumio volumio[1225]: UNSET VOLATILE Jul 06 02:02:43 volumio volumio[1225]: info: CorePlayQueue::clearPlayQueue Jul 06 02:02:43 volumio volumio[1225]: info: CorePlayQueue::saveQueue Jul 06 02:02:43 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:43 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:43 volumio volumio[1225]: info: CoreCommandRouter::volumioPushQueue Jul 06 02:02:43 volumio volumio[1225]: info: Jul 06 02:02:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:43 volumio volumio[1225]: error: updateQueue error: null Jul 06 02:02:43 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:43 volumio volumio[1225]: info: Starting UPNP Playback Jul 06 02:02:43 volumio volumio[1225]: info: Preparing playback through UPNP Jul 06 02:02:43 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Jul 06 02:02:43 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:43 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:44 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:44 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:44 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:44 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:44 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:44 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:44 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:44 volumio volumio[1225]: info: Jul 06 02:02:44 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:44 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:44 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:02:44 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:02:44 volumio volumio[1225]: In UPNP mode Jul 06 02:02:44 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102743/b098ba64f8b393494233c20da7ecedee/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:02:44 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 13ms Jul 06 02:02:44 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:02:44 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:02:44 volumio volumio[1225]: In UPNP mode Jul 06 02:02:44 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102743/b098ba64f8b393494233c20da7ecedee/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:02:44 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus play Jul 06 02:02:44 volumio volumio[1225]: info: Received an update from plugin. extracting info from payload Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:44 volumio volumio[1225]: info: ------------------------------ 22ms Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:44 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:44 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:44 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:44 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:56 volumio volumio[1225]: info: Clearing queue after UPNP request Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::ClearQueue Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::stop Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 02:02:56 volumio volumio[1225]: UNSET VOLATILE Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::stPlaybackTimer Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::updateTrackBlock Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getTrackBlock Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::serviceStop Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:56 volumio volumio[1225]: info: ControllerMpd::stop Jul 06 02:02:56 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::clearPlayQueue Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::saveQueue Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioPushQueue Jul 06 02:02:56 volumio volumio[1225]: info: Jul 06 02:02:56 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:56 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:56 volumio volumio[1225]: info: Jul 06 02:02:56 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:56 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:56 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:56 volumio volumio[1225]: info: Starting UPNP Playback Jul 06 02:02:56 volumio volumio[1225]: info: Preparing playback through UPNP Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 02:02:56 volumio volumio[1225]: error: updateQueue error: null Jul 06 02:02:56 volumio volumio[1225]: info: ------------------------------ 3ms Jul 06 02:02:56 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:56 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:56 volumio volumio[1225]: 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} Jul 06 02:02:56 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::syncState stateService stop Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:56 volumio volumio[1225]: info: No code Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:56 volumio volumio[1225]: info: ------------------------------ 13ms Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:56 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:56 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:56 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:57 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:57 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:57 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:57 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:57 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:57 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:57 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:57 volumio volumio[1225]: info: Jul 06 02:02:57 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:57 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:02:57 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:02:57 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:02:57 volumio volumio[1225]: In UPNP mode Jul 06 02:02:57 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102756/debf05ffcb77defe538d9aea716be6ff/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:02:57 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:57 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:02:57 volumio volumio[1225]: In UPNP mode Jul 06 02:02:57 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102756/debf05ffcb77defe538d9aea716be6ff/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:02:57 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus play Jul 06 02:02:57 volumio volumio[1225]: info: Received an update from plugin. extracting info from payload Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 24ms Jul 06 02:02:57 volumio volumio[1225]: info: ------------------------------ 24ms Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:57 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:57 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:57 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:57 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:57 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:59 volumio volumio[1225]: info: Clearing queue after UPNP request Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::ClearQueue Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::stop Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 02:02:59 volumio volumio[1225]: UNSET VOLATILE Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::stPlaybackTimer Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::updateTrackBlock Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getTrackBlock Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::serviceStop Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:59 volumio volumio[1225]: info: ControllerMpd::stop Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::clearPlayQueue Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::saveQueue Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioPushQueue Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:59 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:59 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:59 volumio volumio[1225]: info: Starting UPNP Playback Jul 06 02:02:59 volumio volumio[1225]: info: Preparing playback through UPNP Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 02:02:59 volumio volumio[1225]: error: updateQueue error: null Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 3ms Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:59 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:02:59 volumio volumio[1225]: 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} Jul 06 02:02:59 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::syncState stateService stop Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:59 volumio volumio[1225]: info: No code Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 12ms Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:02:59 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:59 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:59 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:59 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:59 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:59 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:02:59 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:59 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:59 volumio volumio[1225]: info: Jul 06 02:02:59 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:02:59 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:02:59 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:02:59 volumio volumio[1225]: In UPNP mode Jul 06 02:02:59 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":197,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102759/72bf79e064189e2833e5c31a3ee48294/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:02:59 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:02:59 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:02:59 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:02:59 volumio volumio[1225]: info: ------------------------------ 10ms Jul 06 02:02:59 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:02:59 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:03:00 volumio volumio[1225]: In UPNP mode Jul 06 02:03:00 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102759/72bf79e064189e2833e5c31a3ee48294/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:03:00 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:03:00 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:03:00 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus play Jul 06 02:03:00 volumio volumio[1225]: info: Received an update from plugin. extracting info from payload Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 06 02:03:00 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:00 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:00 volumio volumio[1225]: info: ------------------------------ 17ms Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:00 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:00 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:00 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:00 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:00 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:00 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:00 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:01 volumio volumio[1225]: info: Clearing queue after UPNP request Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::ClearQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::stop Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 02:03:02 volumio volumio[1225]: UNSET VOLATILE Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::stPlaybackTimer Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::updateTrackBlock Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getTrackBlock Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::serviceStop Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:03:02 volumio volumio[1225]: info: ControllerMpd::stop Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand stop Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::clearPlayQueue Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::saveQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushQueue Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:02 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:03:02 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:03:02 volumio volumio[1225]: info: Starting UPNP Playback Jul 06 02:03:02 volumio volumio[1225]: info: Preparing playback through UPNP Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 02:03:02 volumio volumio[1225]: error: updateQueue error: null Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 3ms Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:03:02 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:03:02 volumio volumio[1225]: 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} Jul 06 02:03:02 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::syncState stateService stop Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: No code Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 17ms Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:02 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:02 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:02 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:02 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:02 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:02 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:03:02 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:03:02 volumio volumio[1225]: info: Jul 06 02:03:02 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:03:02 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:03:02 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:03:02 volumio volumio[1225]: In UPNP mode Jul 06 02:03:02 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102801/a1fdebd791bb0ef271c8e79d32c11de6/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:03:02 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 11ms Jul 06 02:03:02 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:03:02 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:03:02 volumio volumio[1225]: In UPNP mode Jul 06 02:03:02 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m802.music.126.net/20240706102801/a1fdebd791bb0ef271c8e79d32c11de6/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:03:02 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus play Jul 06 02:03:02 volumio volumio[1225]: info: Received an update from plugin. extracting info from payload Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:02 volumio volumio[1225]: info: ------------------------------ 17ms Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:02 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:02 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:16 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Jul 06 02:03:16 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 02:03:16 volumio volumio[1225]: info: Listing playlists Jul 06 02:03:16 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:16 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:16 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:16 volumio volumio[1225]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 02:03:16 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 02:03:31 volumio volumio[1225]: info: Jul 06 02:03:31 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:03:31 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:03:31 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:03:31 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 06 02:03:31 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:03:31 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:03:31 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:03:31 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:03:31 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:03:31 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:31 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:31 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:31 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:03:31 volumio volumio[1225]: 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":"I'm O.K.","uri":"http://m802.music.126.net/20240706102801/a1fdebd791bb0ef271c8e79d32c11de6/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:03:31 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:03:31 volumio volumio[1225]: info: CoreStateMachine::syncState stateService stop Jul 06 02:03:31 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus play Jul 06 02:03:31 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:31 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:31 volumio volumio[1225]: info: CoreStateMachine::stPlaybackTimer Jul 06 02:03:31 volumio volumio[1225]: info: ------------------------------ 13ms Jul 06 02:03:31 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:31 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:31 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:42 volumio volumio[1225]: info: Clearing queue after UPNP request Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::ClearQueue Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::stop Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 06 02:03:43 volumio volumio[1225]: UNSET VOLATILE Jul 06 02:03:43 volumio volumio[1225]: info: CorePlayQueue::clearPlayQueue Jul 06 02:03:43 volumio volumio[1225]: info: CorePlayQueue::saveQueue Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::volumioPushQueue Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:43 volumio volumio[1225]: error: updateQueue error: null Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:03:43 volumio volumio[1225]: info: Starting UPNP Playback Jul 06 02:03:43 volumio volumio[1225]: info: Preparing playback through UPNP Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Jul 06 02:03:43 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 1ms Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces system playlist update Jul 06 02:03:43 volumio volumio[1225]: info: Ignoring MPD Status Update Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 2ms Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:03:43 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:03:43 volumio volumio[1225]: info: Jul 06 02:03:43 volumio volumio[1225]: ---------------------------- MPD announces state update: player Jul 06 02:03:43 volumio volumio[1225]: info: ControllerMpd::getState Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::parseState Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:03:43 volumio volumio[1225]: verbose: ControllerMpd::parseTrackInfo Jul 06 02:03:43 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:03:43 volumio volumio[1225]: In UPNP mode Jul 06 02:03:43 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m702.music.126.net/20240706102842/463bfffbeb3823fdccfb549258fa2e5d/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:03:43 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:03:43 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:43 volumio volumio[1225]: info: ControllerMpd::pushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Jul 06 02:03:43 volumio volumio[1225]: In UPNP mode Jul 06 02:03:43 volumio volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"天天","artist":"陶喆","album":"I'm O.K.","uri":"http://m702.music.126.net/20240706102842/463bfffbeb3823fdccfb549258fa2e5d/jd-musicrep-ts/8361/a068/95d1/fed87e434722003715cd1e43ea453da7.","trackType":""} Jul 06 02:03:43 volumio volumio[1225]: verbose: CURRENT POSITION 0 Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::syncState stateService play Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::syncState currentStatus play Jul 06 02:03:43 volumio volumio[1225]: info: Received an update from plugin. extracting info from payload Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:43 volumio volumio[1225]: info: CoreStateMachine::pushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Jul 06 02:03:43 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 26ms Jul 06 02:03:43 volumio volumio[1225]: info: ------------------------------ 25ms Jul 06 02:03:44 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:44 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:44 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:44 volumio volumio[1225]: info: CoreCommandRouter::volumioGetQueue Jul 06 02:03:44 volumio volumio[1225]: info: CoreStateMachine::getQueue Jul 06 02:03:44 volumio volumio[1225]: info: CorePlayQueue::getQueue Jul 06 02:03:44 volumio volumio[1225]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%B6%E5%96%86/I%27m%20O.K./f95fa482-8ae2-4334-908a-4761490702f7.jpg' Jul 06 02:03:50 volumio volumio[1225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 02:03:50 volumio volumio[1225]: { Error: connect ETIMEDOUT 199.59.149.231:443 Jul 06 02:03:50 volumio volumio[1225]: at Object._errnoException (util.js:1022:11) Jul 06 02:03:50 volumio volumio[1225]: at _exceptionWithHostPort (util.js:1044:20) Jul 06 02:03:50 volumio volumio[1225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 06 02:03:50 volumio volumio[1225]: code: 'ETIMEDOUT', Jul 06 02:03:50 volumio volumio[1225]: errno: 'ETIMEDOUT', Jul 06 02:03:50 volumio volumio[1225]: syscall: 'connect', Jul 06 02:03:50 volumio volumio[1225]: address: '199.59.149.231', Jul 06 02:03:50 volumio volumio[1225]: port: 443 } Jul 06 02:03:50 volumio volumio[1225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 02:03:50 volumio sudo[2033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-06 02:02 Jul 06 02:03:50 volumio sudo[2033]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"