-- Logs begin at Wed 2024-08-28 10:17:45 UTC, end at Wed 2024-08-28 11:02:42 UTC. -- Aug 28 11:01:00 volumio wireless.js[2375]: trying... Aug 28 11:01:00 volumio sudo[2830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 11:01:00 volumio sudo[2830]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:00 volumio sudo[2830]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:01 volumio wireless.js[2375]: trying... Aug 28 11:01:01 volumio sudo[2841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 11:01:01 volumio sudo[2841]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:01 volumio sudo[2841]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:02 volumio wireless.js[2375]: trying... Aug 28 11:01:02 volumio sudo[2852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 28 11:01:02 volumio sudo[2852]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:02 volumio sudo[2852]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:03 volumio volumio[1228]: info: Retrieving Cloud Streaming UI Aug 28 11:01:03 volumio volumio[1228]: info: Getting Tidal Cloud Configuration Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:03 volumio volumio[1228]: info: Getting Qobuz Cloud Configuration Aug 28 11:01:03 volumio volumio[1228]: info: Asking plugin for UI Config Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:03 volumio volumio[1228]: info: Getting Spotify Cloud Configuration Aug 28 11:01:03 volumio volumio[1228]: info: Asking plugin for UI Config Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:03 volumio volumio[1228]: info: Saving Spotify Acccount Aug 28 11:01:03 volumio volumio[1228]: info: Got Tidal Cloud Configuration Aug 28 11:01:03 volumio volumio[1228]: info: Got it Aug 28 11:01:03 volumio volumio[1228]: info: Got it Aug 28 11:01:03 volumio volumio[1228]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:03 volumio volumio[1228]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 11:01:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 28 11:01:03 volumio wireless.js[2375]: Overtime, starting plan B Aug 28 11:01:03 volumio systemd[1]: Started Wireless Services. Aug 28 11:01:03 volumio sudo[2357]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:05 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="ChinaNet-CRFG" Aug 28 11:01:05 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="ChinaNet-CRFG" Aug 28 11:01:05 volumio wpa_supplicant[2486]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG' Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 28 11:01:06 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand rescan Aug 28 11:01:06 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:06 volumio volumio[1228]: info: Aug 28 11:01:06 volumio volumio[1228]: ---------------------------- MPD announces state update: update Aug 28 11:01:06 volumio volumio[1228]: info: sendMpdCommand rescan took 2 milliseconds Aug 28 11:01:06 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:06 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:06 volumio volumio[1228]: info: sendMpdCommand status took 2 milliseconds Aug 28 11:01:06 volumio volumio[1228]: info: sendMpdCommand status took 2 milliseconds Aug 28 11:01:06 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:06 volumio volumio[1228]: info: Command Router : Notfying DB Updatetrue Aug 28 11:01:06 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:06 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:06 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:06 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:06 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:06 volumio volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 11:01:06 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:06 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:06 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:06 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:06 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:06 volumio volumio[1228]: info: No code Aug 28 11:01:06 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:06 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:06 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:06 volumio volumio[1228]: info: ------------------------------ 96ms Aug 28 11:01:07 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 28 11:01:07 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ChinaNet-CRFG" auth_failures=2 duration=23 reason=CONN_FAILED Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 11:01:07 volumio volumio[1228]: info: MPD Database updated - AlbumList cache refreshed Aug 28 11:01:07 volumio volumio[1228]: info: Aug 28 11:01:07 volumio volumio[1228]: ---------------------------- MPD announces state update: database Aug 28 11:01:07 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:07 volumio volumio[1228]: info: Aug 28 11:01:07 volumio volumio[1228]: ---------------------------- MPD announces state update: update Aug 28 11:01:07 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:07 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:07 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:07 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:07 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Aug 28 11:01:07 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Aug 28 11:01:07 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Aug 28 11:01:07 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:07 volumio volumio[1228]: info: Command Router : Notfying DB Updatefalse Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::Close All Modals sent Aug 28 11:01:07 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:07 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:07 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: 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} Aug 28 11:01:07 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:07 volumio volumio[1228]: info: No code Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:07 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: 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} Aug 28 11:01:07 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:07 volumio volumio[1228]: info: No code Aug 28 11:01:07 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:07 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:07 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:07 volumio volumio[1228]: info: ------------------------------ 59ms Aug 28 11:01:07 volumio volumio[1228]: info: ------------------------------ 60ms Aug 28 11:01:08 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Aug 28 11:01:08 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Aug 28 11:01:08 volumio sudo[2882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:01:08 volumio sudo[2882]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:08 volumio sudo[2882]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand rescan Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:10 volumio volumio[1228]: info: Aug 28 11:01:10 volumio volumio[1228]: ---------------------------- MPD announces state update: update Aug 28 11:01:10 volumio volumio[1228]: info: sendMpdCommand rescan took 2 milliseconds Aug 28 11:01:10 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:10 volumio volumio[1228]: info: sendMpdCommand status took 2 milliseconds Aug 28 11:01:10 volumio volumio[1228]: info: sendMpdCommand status took 2 milliseconds Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:10 volumio volumio[1228]: info: Command Router : Notfying DB Updatetrue Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::Close All Modals sent Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:10 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 11:01:10 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: No code Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: ------------------------------ 40ms Aug 28 11:01:10 volumio volumio[1228]: info: MPD Database updated - AlbumList cache refreshed Aug 28 11:01:10 volumio volumio[1228]: info: Aug 28 11:01:10 volumio volumio[1228]: ---------------------------- MPD announces state update: database Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:10 volumio volumio[1228]: info: Aug 28 11:01:10 volumio volumio[1228]: ---------------------------- MPD announces state update: update Aug 28 11:01:10 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:10 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:10 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Aug 28 11:01:10 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Aug 28 11:01:10 volumio volumio[1228]: info: sendMpdCommand status took 3 milliseconds Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:10 volumio volumio[1228]: info: Command Router : Notfying DB Updatefalse Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::Close All Modals sent Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:10 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:10 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: 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} Aug 28 11:01:10 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: No code Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: 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} Aug 28 11:01:10 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: No code Aug 28 11:01:10 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:10 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:10 volumio volumio[1228]: info: ------------------------------ 53ms Aug 28 11:01:10 volumio volumio[1228]: info: ------------------------------ 54ms Aug 28 11:01:11 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand update Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:13 volumio volumio[1228]: info: Aug 28 11:01:13 volumio volumio[1228]: ---------------------------- MPD announces state update: update Aug 28 11:01:13 volumio volumio[1228]: info: sendMpdCommand update took 2 milliseconds Aug 28 11:01:13 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:13 volumio volumio[1228]: info: sendMpdCommand status took 2 milliseconds Aug 28 11:01:13 volumio volumio[1228]: info: sendMpdCommand status took 1 milliseconds Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:13 volumio volumio[1228]: info: Command Router : Notfying DB Updatetrue Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::Close All Modals sent Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:13 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 11:01:13 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:13 volumio volumio[1228]: info: No code Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:13 volumio volumio[1228]: info: ------------------------------ 34ms Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:13 volumio volumio[1228]: info: Aug 28 11:01:13 volumio volumio[1228]: ---------------------------- MPD announces state update: update Aug 28 11:01:13 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:01:13 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Aug 28 11:01:13 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:13 volumio volumio[1228]: info: Command Router : Notfying DB Updatefalse Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::Close All Modals sent Aug 28 11:01:13 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:01:13 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: 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} Aug 28 11:01:13 volumio volumio[1228]: verbose: CURRENT POSITION 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::syncState stateService stop Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:13 volumio volumio[1228]: info: No code Aug 28 11:01:13 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:01:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:01:13 volumio volumio[1228]: info: ------------------------------ 30ms Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 28 11:01:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 28 11:01:13 volumio volumio[1228]: info: Refreshing Cached IP Addresses Aug 28 11:01:13 volumio sudo[2911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 28 11:01:13 volumio sudo[2911]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:13 volumio sudo[2913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 11:01:13 volumio sudo[2913]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:13 volumio sudo[2911]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:13 volumio sudo[2918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:01:13 volumio sudo[2918]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:13 volumio sudo[2913]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:13 volumio sudo[2918]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:15 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 11:01:17 volumio wpa_supplicant[2486]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG' Aug 28 11:01:18 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 28 11:01:18 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="ChinaNet-CRFG" auth_failures=2 duration=26 reason=CONN_FAILED Aug 28 11:01:19 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 11:01:22 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:22 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Aug 28 11:01:23 volumio sudo[2944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 11:01:23 volumio sudo[2944]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:23 volumio sudo[2944]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:23 volumio sudo[2946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:01:23 volumio sudo[2946]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:23 volumio sudo[2951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 28 11:01:23 volumio sudo[2951]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:23 volumio sudo[2946]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:23 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 28 11:01:23 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 28 11:01:23 volumio sudo[2951]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:23 volumio volumio[1228]: info: Upmpdcli Daemon Started Aug 28 11:01:28 volumio volumio[1228]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 11:01:28 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 11:01:28 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 28 11:01:28 volumio sudo[2996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 28 11:01:28 volumio sudo[2996]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:28 volumio sudo[3001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 28 11:01:28 volumio sudo[2996]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:28 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:28 volumio sudo[3010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 28 11:01:28 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:28 volumio sudo[3010]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:28 volumio sudo[3021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 28 11:01:28 volumio sudo[3021]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:28 volumio sudo[3031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 11:01:28 volumio sudo[3010]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:28 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 28 11:01:28 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 28 11:01:28 volumio sudo[3031]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:28 volumio sudo[3021]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:28 volumio sudo[3039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:01:28 volumio sudo[3039]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:28 volumio sudo[3031]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:28 volumio sudo[3039]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:28 volumio sudo[3059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 11:01:28 volumio sudo[3059]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:01:30 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="ChinaNet-CRFG" Aug 28 11:01:30 volumio wpa_supplicant[2486]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG' Aug 28 11:01:30 volumio sudo[3059]: pam_unix(sudo:session): session closed for user root Aug 28 11:01:31 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 28 11:01:31 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ChinaNet-CRFG" auth_failures=3 duration=57 reason=CONN_FAILED Aug 28 11:01:41 volumio wpa_supplicant[2486]: wlan0: Failed to initiate sched scan Aug 28 11:01:47 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="ChinaNet-CRFG" Aug 28 11:01:47 volumio wpa_supplicant[2486]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG' Aug 28 11:01:48 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Aug 28 11:01:49 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 28 11:01:49 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="ChinaNet-CRFG" auth_failures=3 duration=55 reason=CONN_FAILED Aug 28 11:01:58 volumio volumio[1228]: error: Cannot download Available plugins list: Error: ETIMEDOUT Aug 28 11:01:59 volumio wpa_supplicant[2486]: wlan0: Failed to initiate sched scan Aug 28 11:02:03 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 11:02:03 volumio volumio[1228]: info: CURURI: music-library Aug 28 11:02:03 volumio volumio[1228]: info: CoreCommandRouter::volumioGetQueue Aug 28 11:02:03 volumio volumio[1228]: info: CoreStateMachine::getQueue Aug 28 11:02:03 volumio volumio[1228]: info: CorePlayQueue::getQueue Aug 28 11:02:05 volumio wpa_supplicant[2486]: wlan0: Failed to initiate sched scan Aug 28 11:02:06 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 11:02:06 volumio volumio[1228]: info: CURURI: music-library/NAS Aug 28 11:02:08 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 11:02:08 volumio volumio[1228]: info: CURURI: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息 Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::ClearQueue Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::stop Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 11:02:09 volumio volumio[1228]: verbose: UNSET VOLATILE Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::clearPlayQueue Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::saveQueue Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::addQueueItems Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::addQueueItems Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Elsa & Emilie - Au Volant.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Elsa & Emilie - Au Volant.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/vietra - fly.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/vietra - fly.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Kayleigh O'Connor - Hurricane.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Kayleigh O'Connor - Hurricane.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Holly Henry - Powder Blue Earth.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Holly Henry - Powder Blue Earth.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Holly Henry - White Knuckles.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Holly Henry - White Knuckles.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Au／Ra - Panic Room (Acoustic).mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Au／Ra - Panic Room (Acoustic).mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Марія Чайковська - Красота.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Марія Чайковська - Красота.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: Adding Item to queue: music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Elsa Kopf - DAYS and MOONS.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: Exploding uri music-library/NAS/｛ 空灵女声 ｝好听到完全窒息/Elsa Kopf - DAYS and MOONS.mp3 in service mpd Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=Elsa%20%26%20Emilie/Au%20Volant/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2FElsa%20%26%20Emilie%20-%20Au%20Volant.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Elsa & Emilie - Au Volant.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=vietra/fly/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2Fvietra%20-%20fly.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/vietra - fly.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=Ella%20Vos/In%20Your%20Corner/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2FElla%20Vos%20-%20In%20Your%20Corner.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=Kayleigh%20O'Connor/Kayleigh%20O'Connor/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2FKayleigh%20O'Connor%20-%20Hurricane.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Kayleigh O'Connor - Hurricane.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=Holly%20Henry/King%20Paten/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2FHolly%20Henry%20-%20Powder%20Blue%20Earth.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Holly Henry - Powder Blue Earth.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=Holly%20Henry/King%20Paten/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2FHolly%20Henry%20-%20White%20Knuckles.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Holly Henry - White Knuckles.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=Au%2FRa/Panic%20Room%20(Acoustic)/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2FAu%EF%BC%8FRa%20-%20Panic%20Room%20(Acoustic).mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Au／Ra - Panic Room (Acoustic).mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=%D0%9C%D0%B0%D1%80%D1%96%D1%8F%20%D0%A7%D0%B0%D0%B9%D0%BA%D0%BE%D0%B2%D1%81%D1%8C%D0%BA%D0%B0/%D0%A6%D0%B5%D0%BB%D1%83%D0%B9%20%D0%9C%D0%B5%D0%BD%D1%8F/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2F%D0%9C%D0%B0%D1%80%D1%96%D1%8F%20%D0%A7%D0%B0%D0%B9%D0%BA%D0%BE%D0%B2%D1%81%D1%8C%D0%BA%D0%B0%20-%20%D0%9A%D1%80%D0%B0%D1%81%D0%BE%D1%82%D0%B0.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Марія Чайковська - Красота.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: ALBUMART /albumart?cacheid=922&web=Elsa%20Kopf/%EC%95%84%EB%A6%84%EB%8B%A4%EC%9A%B4%20%EB%82%98%EC%9D%98%20%EC%8B%A0%EB%B6%80%20OST%20Part.1/extralarge&path=%2Fmnt%2FNAS%2F%EF%BD%9B%20%E7%A9%BA%E7%81%B5%E5%A5%B3%E5%A3%B0%20%EF%BD%9D%E5%A5%BD%E5%90%AC%E5%88%B0%E5%AE%8C%E5%85%A8%E7%AA%92%E6%81%AF%2FElsa%20Kopf%20-%20DAYS%20and%20MOONS.mp3&metadata=false Aug 28 11:02:09 volumio volumio[1228]: info: URI /mnt/NAS/｛ 空灵女声 ｝好听到完全窒息/Elsa Kopf - DAYS and MOONS.mp3 Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::saveQueue Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::updateTrackBlock Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrackBlock Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioPlay Aug 28 11:02:09 volumio volumio[1228]: verbose: UNSET VOLATILE Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::play index 2 Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::stop Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 11:02:09 volumio volumio[1228]: verbose: UNSET VOLATILE Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::play index undefined Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrack 2 Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::startPlaybackTimer Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrack 2 Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::clearAddPlayTracks NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3 Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand stop took 3 milliseconds Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 11:02:09 volumio volumio[1228]: info: Aug 28 11:02:09 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Aug 28 11:02:09 volumio volumio[1228]: info: Ignoring MPD Status Update Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand clear took 3 milliseconds Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand add "NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3" Aug 28 11:02:09 volumio volumio[1228]: error: updateQueue error: null Aug 28 11:02:09 volumio volumio[1228]: info: Aug 28 11:02:09 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Aug 28 11:02:09 volumio volumio[1228]: info: Ignoring MPD Status Update Aug 28 11:02:09 volumio volumio[1228]: info: ------------------------------ 2ms Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand add "NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3" took 2 milliseconds Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand play Aug 28 11:02:09 volumio volumio[1228]: info: ------------------------------ 5ms Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand play took 7 milliseconds Aug 28 11:02:09 volumio volumio[1228]: info: Aug 28 11:02:09 volumio volumio[1228]: ---------------------------- MPD announces state update: player Aug 28 11:02:09 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:02:09 volumio volumio[1228]: info: Aug 28 11:02:09 volumio volumio[1228]: ---------------------------- MPD announces state update: player Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand status took 16 milliseconds Aug 28 11:02:09 volumio volumio[1228]: info: ControllerMpd::getState Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand status took 2 milliseconds Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::parseState Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Aug 28 11:02:09 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrack 2 Aug 28 11:02:09 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"In Your Corner","artist":"Ella Vos","album":"In Your Corner","uri":"NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3","trackType":"mp3"} Aug 28 11:02:09 volumio volumio[1228]: verbose: CURRENT POSITION 2 Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrack 2 Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:02:09 volumio volumio[1228]: info: ------------------------------ 28ms Aug 28 11:02:09 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 28 11:02:09 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Aug 28 11:02:09 volumio volumio[1228]: info: ControllerMpd::pushState Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrack 2 Aug 28 11:02:09 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":199,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"In Your Corner","artist":"Ella Vos","album":"In Your Corner","uri":"NAS/｛ 空灵女声 ｝好听到完全窒息/Ella Vos - In Your Corner.mp3","trackType":"mp3"} Aug 28 11:02:09 volumio volumio[1228]: verbose: CURRENT POSITION 2 Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Aug 28 11:02:09 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrack 2 Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:02:09 volumio volumio[1228]: info: CoreStateMachine::pushState Aug 28 11:02:09 volumio volumio[1228]: info: CorePlayQueue::getTrack 2 Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Aug 28 11:02:09 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:02:09 volumio volumio[1228]: info: ------------------------------ 21ms Aug 28 11:02:10 volumio wpa_supplicant[2486]: wlan0: Failed to initiate sched scan Aug 28 11:02:16 volumio wpa_supplicant[2486]: wlan0: Failed to initiate sched scan Aug 28 11:02:22 volumio wpa_supplicant[2486]: wlan0: Failed to initiate sched scan Aug 28 11:02:28 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="ChinaNet-CRFG" Aug 28 11:02:28 volumio wpa_supplicant[2486]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG' Aug 28 11:02:29 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 28 11:02:29 volumio wpa_supplicant[2486]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ChinaNet-CRFG" auth_failures=4 duration=93 reason=CONN_FAILED Aug 28 11:02:39 volumio wpa_supplicant[2486]: wlan0: Failed to initiate sched scan Aug 28 11:02:42 volumio volumio[1228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 11:02:42 volumio volumio[1228]: { Error: connect ETIMEDOUT 199.59.148.202:443 Aug 28 11:02:42 volumio volumio[1228]: at Object._errnoException (util.js:1022:11) Aug 28 11:02:42 volumio volumio[1228]: at _exceptionWithHostPort (util.js:1044:20) Aug 28 11:02:42 volumio volumio[1228]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 28 11:02:42 volumio volumio[1228]: code: 'ETIMEDOUT', Aug 28 11:02:42 volumio volumio[1228]: errno: 'ETIMEDOUT', Aug 28 11:02:42 volumio volumio[1228]: syscall: 'connect', Aug 28 11:02:42 volumio volumio[1228]: address: '199.59.148.202', Aug 28 11:02:42 volumio volumio[1228]: port: 443 } Aug 28 11:02:42 volumio volumio[1228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 11:02:42 volumio sudo[3268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 11:01 Aug 28 11:02:42 volumio sudo[3268]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"