-- Logs begin at Fri 2025-07-11 11:41:48 CST, end at Fri 2025-07-11 12:21:42 CST. --
Jul 11 12:20:00 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:00 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:00 volumio sudo[11767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:00 volumio sudo[11767]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:01 volumio sudo[11767]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:02 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:02 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:02 volumio sudo[11773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:02 volumio sudo[11773]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:03 volumio sudo[11773]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:04 volumio volumio[11383]: info: BOOT COMPLETED
Jul 11 12:20:04 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:04 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:04 volumio sudo[11777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:04 volumio sudo[11777]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:05 volumio sudo[11777]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:06 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:06 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:06 volumio sudo[11781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:06 volumio sudo[11781]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:07 volumio sudo[11781]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:08 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:08 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:08 volumio sudo[11784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:08 volumio sudo[11784]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:09 volumio sudo[11784]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioNext
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::next
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::stPlaybackTimer
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::updateTrackBlock
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrackBlock
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 0
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::serviceStop
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 0
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::serviceStop
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::stop
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand stop
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand stop took 12 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::play index undefined
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::startPlaybackTimer
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/06.涛声依旧.flac
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::updateTrackBlock
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrackBlock
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand status took 8 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand stop took 3 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand clear
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:10 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand clear took 9 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand status took 6 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand status took 5 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/06.涛声依旧.flac"
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: 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":null,"uri":"INTERNAL/1049.张蔷 - 月光迪斯科.mp3","trackType":"mp3"}
Jul 11 12:20:10 volumio volumio[11383]: verbose: CURRENT POSITION 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState stateService stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: No code
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: 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 11 12:20:10 volumio volumio[11383]: verbose: CURRENT POSITION 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState stateService stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: No code
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: 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 11 12:20:10 volumio volumio[11383]: verbose: CURRENT POSITION 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState stateService stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: No code
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 135ms
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 124ms
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 122ms
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:10 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:10 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:10 volumio volumio[11383]: error: updateQueue error: null
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:10 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 128ms
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand add "INTERNAL/06.涛声依旧.flac" took 124 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 7ms
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 6ms
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand play
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:10 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:10 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 27ms
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand play took 24 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 25ms
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 21ms
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:10 volumio volumio[11383]: info:
Jul 11 12:20:10 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand status took 32 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand status took 29 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand status took 27 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 28 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 28 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 26 milliseconds
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:10 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"509 Kbps","isStreaming":false,"title":"06.涛声依旧.flac","artist":"群星","album":"爱浪音响试音碟","uri":"INTERNAL/06.涛声依旧.flac","trackType":"flac"}
Jul 11 12:20:10 volumio volumio[11383]: verbose: CURRENT POSITION 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"509 Kbps","isStreaming":false,"title":"06.涛声依旧.flac","artist":"群星","album":"爱浪音响试音碟","uri":"INTERNAL/06.涛声依旧.flac","trackType":"flac"}
Jul 11 12:20:10 volumio volumio[11383]: verbose: CURRENT POSITION 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:10 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"509 Kbps","isStreaming":false,"title":"06.涛声依旧.flac","artist":"群星","album":"爱浪音响试音碟","uri":"INTERNAL/06.涛声依旧.flac","trackType":"flac"}
Jul 11 12:20:10 volumio volumio[11383]: verbose: CURRENT POSITION 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:10 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:10 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 100ms
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 118ms
Jul 11 12:20:10 volumio volumio[11383]: info: ------------------------------ 116ms
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:10 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:10 volumio sudo[11802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:10 volumio sudo[11802]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:11 volumio sudo[11802]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:12 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:12 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:12 volumio sudo[11806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:12 volumio sudo[11806]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:13 volumio sudo[11806]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:14 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:14 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:14 volumio sudo[11809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:14 volumio sudo[11809]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:15 volumio sudo[11809]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:16 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:16 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:16 volumio sudo[11813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:16 volumio sudo[11813]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:17 volumio sudo[11813]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:18 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:18 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:18 volumio sudo[11817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:18 volumio sudo[11817]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:19 volumio sudo[11817]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:20 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:20 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:20 volumio sudo[11875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:20 volumio sudo[11875]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:21 volumio sudo[11875]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:22 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:22 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:22 volumio sudo[11882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:22 volumio sudo[11882]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:23 volumio sudo[11882]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:24 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:24 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:24 volumio sudo[11887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:24 volumio sudo[11887]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:25 volumio sudo[11887]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:26 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:26 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:26 volumio sudo[11890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:26 volumio sudo[11890]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:27 volumio sudo[11890]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:28 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:28 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:28 volumio sudo[11894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:28 volumio sudo[11894]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:29 volumio sudo[11894]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioNext
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::next
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::stPlaybackTimer
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::updateTrackBlock
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrackBlock
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::serviceStop
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 1
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::serviceStop
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::stop
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand stop
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand stop took 15 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::play index undefined
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::startPlaybackTimer
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/野百合也有春天.flac
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::updateTrackBlock
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrackBlock
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 5 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand stop took 2 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand clear
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:30 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 8 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand clear took 7 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 6 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 4 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/野百合也有春天.flac"
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: 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":"06.涛声依旧.flac","artist":"群星","album":"爱浪音响试音碟","uri":"INTERNAL/06.涛声依旧.flac","trackType":"flac"}
Jul 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: No code
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: 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 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: No code
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: 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 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: No code
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 119ms
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 111ms
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 110ms
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:30 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:30 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:30 volumio volumio[11383]: error: updateQueue error: null
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:30 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 116ms
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand add "INTERNAL/野百合也有春天.flac" took 113 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 7ms
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 6ms
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand play
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:30 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:30 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 23ms
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand play took 21 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 20ms
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 17ms
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 10 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 22 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 21 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 16 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 15 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 14 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"705 Kbps","isStreaming":false,"title":"Track 10","artist":"Unknown Artist","album":"Unknown Album (ripping started: 2021-05-09 06:06)","uri":"INTERNAL/野百合也有春天.flac","trackType":"flac"}
Jul 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 43ms
Jul 11 12:20:30 volumio volumio[11383]: info:
Jul 11 12:20:30 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 16 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 13 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 13 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 11 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand status took 5 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"705 Kbps","isStreaming":false,"title":"Track 10","artist":"Unknown Artist","album":"Unknown Album (ripping started: 2021-05-09 06:06)","uri":"INTERNAL/野百合也有春天.flac","trackType":"flac"}
Jul 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:30 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"Track 10","artist":"Unknown Artist","album":"Unknown Album (ripping started: 2021-05-09 06:06)","uri":"INTERNAL/野百合也有春天.flac","trackType":"flac"}
Jul 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:30 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"Track 10","artist":"Unknown Artist","album":"Unknown Album (ripping started: 2021-05-09 06:06)","uri":"INTERNAL/野百合也有春天.flac","trackType":"flac"}
Jul 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:30 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"791 Kbps","isStreaming":false,"title":"Track 10","artist":"Unknown Artist","album":"Unknown Album (ripping started: 2021-05-09 06:06)","uri":"INTERNAL/野百合也有春天.flac","trackType":"flac"}
Jul 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:30 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 163ms
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 164ms
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 161ms
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 158ms
Jul 11 12:20:30 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 133 milliseconds
Jul 11 12:20:30 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:30 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"774 Kbps","isStreaming":false,"title":"Track 10","artist":"Unknown Artist","album":"Unknown Album (ripping started: 2021-05-09 06:06)","uri":"INTERNAL/野百合也有春天.flac","trackType":"flac"}
Jul 11 12:20:30 volumio volumio[11383]: verbose: CURRENT POSITION 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:30 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:30 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:30 volumio volumio[11383]: info: ------------------------------ 176ms
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:30 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:30 volumio sudo[11912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:30 volumio sudo[11912]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:31 volumio sudo[11912]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:32 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:32 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:33 volumio sudo[11915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:33 volumio sudo[11915]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:33 volumio sudo[11915]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:34 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:34 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:35 volumio sudo[11919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:35 volumio sudo[11919]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:35 volumio sudo[11919]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:37 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:37 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:37 volumio sudo[11923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:37 volumio sudo[11923]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:37 volumio sudo[11923]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioNext
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::next
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::stop
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::stPlaybackTimer
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::updateTrackBlock
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrackBlock
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::serviceStop
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 2
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::serviceStop
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::stop
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand stop
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand stop took 10 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::play index undefined
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::startPlaybackTimer
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/2938.黑豹乐队 - Don't Break My Heart.mp3
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand stop
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::updateTrackBlock
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrackBlock
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand status took 9 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand stop took 7 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand status took 4 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand status took 3 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand clear
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:38 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand clear took 4 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 5 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/2938.黑豹乐队 - Don't Break My Heart.mp3"
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: 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":"Track 10","artist":"Unknown Artist","album":"Unknown Album (ripping started: 2021-05-09 06:06)","uri":"INTERNAL/野百合也有春天.flac","trackType":"flac"}
Jul 11 12:20:38 volumio volumio[11383]: verbose: CURRENT POSITION 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState stateService stop
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: info: No code
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 66ms
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 61ms
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 59ms
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:38 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:38 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:38 volumio volumio[11383]: error: updateQueue error: null
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:38 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 58ms
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand add "INTERNAL/2938.黑豹乐队 - Don't Break My Heart.mp3" took 55 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 7ms
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 5ms
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand play
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:38 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces system playlist update
Jul 11 12:20:38 volumio volumio[11383]: info: Ignoring MPD Status Update
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 13ms
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand play took 12 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 11ms
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 16ms
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:38 volumio volumio[11383]: info:
Jul 11 12:20:38 volumio volumio[11383]: ---------------------------- MPD announces state update: player
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::getState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand status
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand status took 8 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand status took 7 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand status took 5 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseState
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 14 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 14 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: info: sendMpdCommand playlistinfo took 13 milliseconds
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:38 volumio volumio[11383]: verbose: ControllerMpd::parseTrackInfo
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Break My Heart","artist":"黑豹乐队","album":"无地自容","uri":"INTERNAL/2938.黑豹乐队 - Don't Break My Heart.mp3","trackType":"mp3"}
Jul 11 12:20:38 volumio volumio[11383]: verbose: CURRENT POSITION 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus stop
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Break My Heart","artist":"黑豹乐队","album":"无地自容","uri":"INTERNAL/2938.黑豹乐队 - Don't Break My Heart.mp3","trackType":"mp3"}
Jul 11 12:20:38 volumio volumio[11383]: verbose: CURRENT POSITION 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:38 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: info: ControllerMpd::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::servicePushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't Break My Heart","artist":"黑豹乐队","album":"无地自容","uri":"INTERNAL/2938.黑豹乐队 - Don't Break My Heart.mp3","trackType":"mp3"}
Jul 11 12:20:38 volumio volumio[11383]: verbose: CURRENT POSITION 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState stateService play
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::syncState currentStatus play
Jul 11 12:20:38 volumio volumio[11383]: info: Received an update from plugin. extracting info from payload
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: info: CoreStateMachine::pushState
Jul 11 12:20:38 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:38 volumio volumio[11383]: info: CoreCommandRouter::volumioPushState
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 55ms
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 91ms
Jul 11 12:20:38 volumio volumio[11383]: info: ------------------------------ 89ms
Jul 11 12:20:38 volumio volumio[11383]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%91%E8%B1%B9%E4%B9%90%E9%98%9F/%E6%97%A0%E5%9C%B0%E8%87%AA%E5%AE%B9/d01cc78a-1fe2-4cc1-9979-21c22b7dc5ce.jpg'
Jul 11 12:20:39 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:39 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:39 volumio sudo[11926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:39 volumio sudo[11926]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:39 volumio sudo[11926]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:41 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:41 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:41 volumio sudo[11945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:41 volumio sudo[11945]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:41 volumio sudo[11945]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:43 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:43 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:43 volumio sudo[11952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:43 volumio sudo[11952]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:43 volumio sudo[11952]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:45 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:45 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:45 volumio sudo[11955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:45 volumio sudo[11955]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:45 volumio sudo[11955]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:47 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:47 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:47 volumio sudo[11959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:47 volumio sudo[11959]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:47 volumio sudo[11959]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:49 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:49 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:49 volumio sudo[11964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:49 volumio sudo[11964]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:49 volumio sudo[11964]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:51 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:51 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:51 volumio sudo[11981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:51 volumio sudo[11981]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:51 volumio sudo[11981]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:53 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:53 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:53 volumio sudo[11985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:53 volumio sudo[11985]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:53 volumio sudo[11985]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:55 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:55 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:55 volumio sudo[11989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:55 volumio sudo[11989]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:56 volumio sudo[11989]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:57 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:57 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:57 volumio sudo[11992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:57 volumio sudo[11992]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:20:58 volumio sudo[11992]: pam_unix(sudo:session): session closed for user root
Jul 11 12:20:58 volumio volumio[11383]: info: CoreCommandRouter::volumioGetState
Jul 11 12:20:58 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:20:59 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 11 12:20:59 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 11 12:20:59 volumio sudo[11996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 11 12:20:59 volumio sudo[11996]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 11 12:21:00 volumio sudo[11996]: pam_unix(sudo:session): session closed for user root
Jul 11 12:21:04 volumio volumio[11383]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 11 12:21:04 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 11 12:21:04 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 11 12:21:04 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 11 12:21:04 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Jul 11 12:21:04 volumio volumio[11383]: info: Received Get System Version
Jul 11 12:21:04 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 11 12:21:22 volumio volumio[11383]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object]
Jul 11 12:21:22 volumio volumio[11383]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI
Jul 11 12:21:22 volumio volumio[11383]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"}
Jul 11 12:21:23 volumio volumio[11383]: info: CoreCommandRouter::Reload Ui
Jul 11 12:21:29 volumio volumio[11383]: info: CoreCommandRouter::volumioGetState
Jul 11 12:21:29 volumio volumio[11383]: info: CorePlayQueue::getTrack 3
Jul 11 12:21:41 volumio volumio[11383]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 11 12:21:41 volumio volumio[11383]: Error: connect ETIMEDOUT 199.16.156.11:443
Jul 11 12:21:41 volumio volumio[11383]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
Jul 11 12:21:41 volumio volumio[11383]: errno: -110,
Jul 11 12:21:41 volumio volumio[11383]: code: 'ETIMEDOUT',
Jul 11 12:21:41 volumio volumio[11383]: syscall: 'connect',
Jul 11 12:21:41 volumio volumio[11383]: address: '199.16.156.11',
Jul 11 12:21:41 volumio volumio[11383]: port: 443
Jul 11 12:21:41 volumio volumio[11383]: }
Jul 11 12:21:41 volumio volumio[11383]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 11 12:21:42 volumio sudo[12157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-11 12:20
Jul 11 12:21:42 volumio sudo[12157]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST"
VOLUMIO_VERSION="3.819"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"