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