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