-- Logs begin at Sat 2024-10-05 19:02:36 MSK, end at Sat 2024-10-05 19:39:50 MSK. -- Oct 05 19:38:01 volumio hostapd[6459]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:01 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:01 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:01 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:01 volumio hostapd[6459]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:01 volumio hostapd[6459]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:01 volumio hostapd[6459]: nl80211 driver initialization failed. Oct 05 19:38:01 volumio hostapd[6459]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:01 volumio hostapd[6459]: wlan0: AP-DISABLED Oct 05 19:38:01 volumio hostapd[6459]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:01 volumio hostapd[6459]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:01 volumio volumio[846]: info: ------------------------------ 9868ms Oct 05 19:38:01 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:01 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:01 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:04 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:04 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2051. Oct 05 19:38:05 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:05 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:05 volumio volumio[846]: info: ------------------------------ 13940ms Oct 05 19:38:05 volumio volumio[846]: info: ------------------------------ 13950ms Oct 05 19:38:06 volumio volumio[846]: info: ------------------------------ 13941ms Oct 05 19:38:07 volumio hostapd[6460]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:07 volumio hostapd[6460]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:07 volumio hostapd[6460]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:07 volumio hostapd[6460]: nl80211 driver initialization failed. Oct 05 19:38:07 volumio hostapd[6460]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:07 volumio hostapd[6460]: wlan0: AP-DISABLED Oct 05 19:38:07 volumio hostapd[6460]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:07 volumio hostapd[6460]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:07 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:07 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:07 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:08 volumio volumio[846]: info: sendMpdCommand playlistinfo took 15917 milliseconds Oct 05 19:38:08 volumio volumio[846]: info: sendMpdCommand playlistinfo took 15865 milliseconds Oct 05 19:38:09 volumio volumio[846]: info: sendMpdCommand playlistinfo took 15859 milliseconds Oct 05 19:38:09 volumio volumio[846]: info: sendMpdCommand playlistinfo took 15851 milliseconds Oct 05 19:38:09 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:09 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:09 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:09 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:09 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:09 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:09 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":514,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Август — это ты","artist":"MOT","album":"АВГУСТ НАВСЕГДА","uri":"USB/1/MOT_-_Avgust_jeto_ty_78182341.mp3","trackType":"mp3"} Oct 05 19:38:09 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:38:09 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:09 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:09 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:09 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:09 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:09 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Август — это ты","artist":"MOT","album":"АВГУСТ НАВСЕГДА","uri":"USB/1/MOT_-_Avgust_jeto_ty_78182341.mp3","trackType":"mp3"} Oct 05 19:38:09 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:38:09 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:09 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:09 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:09 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:09 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:09 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:09 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Август — это ты","artist":"MOT","album":"АВГУСТ НАВСЕГДА","uri":"USB/1/MOT_-_Avgust_jeto_ty_78182341.mp3","trackType":"mp3"} Oct 05 19:38:09 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:38:09 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:10 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:10 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2052. Oct 05 19:38:10 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:38:10 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:38:10 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:10 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:11 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:11 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:11 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:11 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:11 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:11 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Август — это ты","artist":"MOT","album":"АВГУСТ НАВСЕГДА","uri":"USB/1/MOT_-_Avgust_jeto_ty_78182341.mp3","trackType":"mp3"} Oct 05 19:38:11 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:38:11 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:11 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:38:11 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:38:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:11 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:11 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:11 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:12 volumio volumio-remote-updater[505]: [2024-10-05 19:38:12] [disconnect] Disconnect close local:[1006,The closing handshake timed out] remote:[1000] Oct 05 19:38:13 volumio hostapd[6461]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:13 volumio hostapd[6461]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:13 volumio hostapd[6461]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:13 volumio hostapd[6461]: nl80211 driver initialization failed. Oct 05 19:38:13 volumio hostapd[6461]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:13 volumio hostapd[6461]: wlan0: AP-DISABLED Oct 05 19:38:13 volumio hostapd[6461]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:13 volumio hostapd[6461]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:13 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:13 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:13 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:15 volumio volumio[846]: info: ------------------------------ 23873ms Oct 05 19:38:15 volumio volumio[846]: info: ------------------------------ 23781ms Oct 05 19:38:15 volumio volumio[846]: info: ------------------------------ 23780ms Oct 05 19:38:16 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:16 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2053. Oct 05 19:38:16 volumio volumio[846]: info: ------------------------------ 23767ms Oct 05 19:38:17 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:17 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:18 volumio volumio-remote-updater[505]: [2024-10-05 19:38:18] [connect] Successful connection Oct 05 19:38:19 volumio hostapd[6466]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:19 volumio hostapd[6466]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:19 volumio hostapd[6466]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:19 volumio hostapd[6466]: nl80211 driver initialization failed. Oct 05 19:38:19 volumio hostapd[6466]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:19 volumio hostapd[6466]: wlan0: AP-DISABLED Oct 05 19:38:19 volumio hostapd[6466]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:19 volumio hostapd[6466]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:19 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:19 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:20 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:24 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:24 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2054. Oct 05 19:38:25 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:25 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:27 volumio hostapd[6484]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:27 volumio hostapd[6484]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:27 volumio hostapd[6484]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:27 volumio hostapd[6484]: nl80211 driver initialization failed. Oct 05 19:38:27 volumio hostapd[6484]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:27 volumio hostapd[6484]: wlan0: AP-DISABLED Oct 05 19:38:27 volumio hostapd[6484]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:27 volumio hostapd[6484]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:27 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:27 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:27 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:30 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:30 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2055. Oct 05 19:38:31 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:31 volumio volumio[846]: info: CoreCommandRouter::volumioNext Oct 05 19:38:31 volumio volumio[846]: info: CoreStateMachine::next Oct 05 19:38:31 volumio volumio[846]: info: CoreStateMachine::stop Oct 05 19:38:31 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:38:31 volumio volumio[846]: info: CoreStateMachine::stPlaybackTimer Oct 05 19:38:31 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:31 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:38:31 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:38:31 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:31 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:31 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:38:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:32 volumio volumio-remote-updater[505]: [2024-10-05 19:38:32] [connect] Successful connection Oct 05 19:38:33 volumio hostapd[6494]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:33 volumio volumio[846]: info: CoreStateMachine::serviceStop Oct 05 19:38:33 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:33 volumio volumio[846]: info: CoreCommandRouter::serviceStop Oct 05 19:38:33 volumio volumio[846]: info: ControllerMpd::stop Oct 05 19:38:33 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:38:33 volumio hostapd[6494]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:33 volumio hostapd[6494]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:33 volumio hostapd[6494]: nl80211 driver initialization failed. Oct 05 19:38:33 volumio hostapd[6494]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:33 volumio hostapd[6494]: wlan0: AP-DISABLED Oct 05 19:38:33 volumio hostapd[6494]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:33 volumio hostapd[6494]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:33 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:33 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:33 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:35 volumio volumio[846]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. Oct 05 19:38:35 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:38:35 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand stop took 2043 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::play index undefined Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::startPlaybackTimer Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::clearAddPlayTracks USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3 Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:35 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:35 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:35 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand status took 268 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand stop took 240 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand status took 230 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand status took 213 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand status took 206 milliseconds Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:35 volumio volumio-remote-updater[505]: [2024-10-05 19:38:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728146312 101 Oct 05 19:38:35 volumio volumio[846]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand playlistinfo took 117 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand clear took 117 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand playlistinfo took 116 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand playlistinfo took 115 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand playlistinfo took 114 milliseconds Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand add "USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3" Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:35 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:35 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:35 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:38:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:35 volumio volumio[846]: 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":"MOT","album":"АВГУСТ НАВСЕГДА","uri":"USB/1/MOT_-_Avgust_jeto_ty_78182341.mp3","trackType":"mp3"} Oct 05 19:38:35 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:35 volumio volumio[846]: info: No code Oct 05 19:38:35 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:35 volumio volumio[846]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 05 19:38:35 volumio volumio[846]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 05 19:38:35 volumio volumio[846]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 587ms Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 533ms Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 526ms Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 508ms Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: error: updateQueue error: null Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 228ms Oct 05 19:38:35 volumio volumio[846]: info: sendMpdCommand add "USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3" took 212 milliseconds Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 47ms Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 39ms Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 32ms Oct 05 19:38:35 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand play Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:38:35 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:38:35 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:38:35 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:35 volumio volumio[846]: info: Oct 05 19:38:35 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:35 volumio volumio[846]: info: ------------------------------ 228ms Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand play took 216 milliseconds Oct 05 19:38:36 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:36 volumio volumio[846]: info: ------------------------------ 216ms Oct 05 19:38:36 volumio volumio[846]: info: ------------------------------ 114ms Oct 05 19:38:36 volumio volumio[846]: info: ------------------------------ 101ms Oct 05 19:38:36 volumio volumio[846]: info: Oct 05 19:38:36 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:36 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:36 volumio volumio[846]: info: Oct 05 19:38:36 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:36 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:36 volumio volumio[846]: info: Oct 05 19:38:36 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:38:36 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand status took 80 milliseconds Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand status took 82 milliseconds Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand status took 70 milliseconds Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand status took 60 milliseconds Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand playlistinfo took 65 milliseconds Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand playlistinfo took 64 milliseconds Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand playlistinfo took 54 milliseconds Oct 05 19:38:36 volumio volumio[846]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:36 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:38:36 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:36 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:36 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:37 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:37 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2056. Oct 05 19:38:37 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:38:37 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:38:37 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:38 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:38:38 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:38 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:38 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:38 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:38:38 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:38:38 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:38 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:38:38 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:38:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:38 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:38 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:38:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:38 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:38 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:40 volumio hostapd[6507]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:40 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:40 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:40 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:40 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:40 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:38:40 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:38:40 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:40 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:38:40 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:38:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:40 volumio hostapd[6507]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:40 volumio hostapd[6507]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:40 volumio hostapd[6507]: nl80211 driver initialization failed. Oct 05 19:38:40 volumio hostapd[6507]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:40 volumio hostapd[6507]: wlan0: AP-DISABLED Oct 05 19:38:40 volumio hostapd[6507]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:40 volumio hostapd[6507]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:40 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:40 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:40 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:40 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:40 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:40 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:38:40 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:38:40 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:40 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:38:40 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:38:40 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:38:40 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:38:41 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:38:41 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:41 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:41 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:41 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:38:41 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:42 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:38:42 volumio volumio[846]: info: ------------------------------ 6022ms Oct 05 19:38:43 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:43 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2057. Oct 05 19:38:44 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:44 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:44 volumio volumio[846]: info: ------------------------------ 8147ms Oct 05 19:38:45 volumio volumio[846]: info: ------------------------------ 9950ms Oct 05 19:38:46 volumio volumio[846]: info: ------------------------------ 9950ms Oct 05 19:38:46 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:38:46 volumio hostapd[6510]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:46 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:46 volumio hostapd[6510]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:46 volumio hostapd[6510]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:46 volumio hostapd[6510]: nl80211 driver initialization failed. Oct 05 19:38:46 volumio hostapd[6510]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:46 volumio hostapd[6510]: wlan0: AP-DISABLED Oct 05 19:38:46 volumio hostapd[6510]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:46 volumio hostapd[6510]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:46 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:46 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:46 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:49 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:49 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2058. Oct 05 19:38:50 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:50 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:51 volumio hostapd[6513]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:52 volumio hostapd[6513]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:52 volumio hostapd[6513]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:52 volumio hostapd[6513]: nl80211 driver initialization failed. Oct 05 19:38:52 volumio hostapd[6513]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:52 volumio hostapd[6513]: wlan0: AP-DISABLED Oct 05 19:38:52 volumio hostapd[6513]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:52 volumio hostapd[6513]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:52 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:52 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:52 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:52 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:38:52 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:38:52 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:38:52 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:38:52 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:38:54 volumio volumio[846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 05 19:38:54 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:38:54 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:38:55 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:38:55 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2059. Oct 05 19:38:56 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:38:56 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:38:57 volumio hostapd[6514]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:38:58 volumio hostapd[6514]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:38:58 volumio hostapd[6514]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:38:58 volumio hostapd[6514]: nl80211 driver initialization failed. Oct 05 19:38:58 volumio hostapd[6514]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:38:58 volumio hostapd[6514]: wlan0: AP-DISABLED Oct 05 19:38:58 volumio hostapd[6514]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:38:58 volumio hostapd[6514]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:38:58 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:38:58 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:38:58 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:00 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:00 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:00 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:39:00 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:39:01 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:01 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2060. Oct 05 19:39:01 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:02 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:02 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:02 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:02 volumio hostapd[6516]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:02 volumio hostapd[6516]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:03 volumio hostapd[6516]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:03 volumio hostapd[6516]: nl80211 driver initialization failed. Oct 05 19:39:03 volumio hostapd[6516]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:03 volumio hostapd[6516]: wlan0: AP-DISABLED Oct 05 19:39:03 volumio hostapd[6516]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:03 volumio hostapd[6516]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:04 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:04 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:04 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:04 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:04 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:04 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:04 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:04 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:04 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:04 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:04 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:04 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:04 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:05 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:05 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:05 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:05 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:05 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:05 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:05 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:06 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:06 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2061. Oct 05 19:39:06 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:06 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:06 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:06 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:06 volumio hostapd[6524]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:06 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:06 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:06 volumio hostapd[6524]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:06 volumio hostapd[6524]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:06 volumio hostapd[6524]: nl80211 driver initialization failed. Oct 05 19:39:06 volumio hostapd[6524]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:06 volumio hostapd[6524]: wlan0: AP-DISABLED Oct 05 19:39:06 volumio hostapd[6524]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:06 volumio hostapd[6524]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:06 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:06 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:06 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:06 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:06 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:06 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:06 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:07 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:07 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:07 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:07 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:07 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:07 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:07 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:08 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:08 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:08 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:08 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:08 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:08 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:08 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:08 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2062. Oct 05 19:39:08 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:08 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:08 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:08 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:08 volumio hostapd[6529]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:08 volumio hostapd[6529]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:08 volumio hostapd[6529]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:08 volumio hostapd[6529]: nl80211 driver initialization failed. Oct 05 19:39:08 volumio hostapd[6529]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:08 volumio hostapd[6529]: wlan0: AP-DISABLED Oct 05 19:39:08 volumio hostapd[6529]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:08 volumio hostapd[6529]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:09 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:09 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:09 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:09 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:09 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:09 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:09 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:09 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:09 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:09 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:09 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:09 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:09 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:09 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:09 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:10 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:10 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:10 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:10 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:10 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:10 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: info: ControllerMpd::seek Oct 05 19:39:10 volumio volumio[846]: error: null Oct 05 19:39:10 volumio volumio[846]: info: Oct 05 19:39:10 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:10 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:10 volumio volumio[846]: info: Oct 05 19:39:10 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:10 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:10 volumio volumio[846]: info: Oct 05 19:39:10 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:10 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:10 volumio volumio[846]: info: Oct 05 19:39:10 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:10 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:10 volumio volumio[846]: info: sendMpdCommand status took 61 milliseconds Oct 05 19:39:10 volumio volumio[846]: info: sendMpdCommand status took 43 milliseconds Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:10 volumio volumio[846]: info: sendMpdCommand status took 54 milliseconds Oct 05 19:39:10 volumio volumio[846]: info: sendMpdCommand status took 50 milliseconds Oct 05 19:39:10 volumio volumio[846]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:10 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:10 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:10 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:10 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:10 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:10 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:10 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:10 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:10 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:10 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:10 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:11 volumio volumio[846]: info: ------------------------------ 419ms Oct 05 19:39:11 volumio volumio[846]: info: sendMpdCommand playlistinfo took 339 milliseconds Oct 05 19:39:11 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:11 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2063. Oct 05 19:39:11 volumio volumio[846]: info: sendMpdCommand playlistinfo took 327 milliseconds Oct 05 19:39:11 volumio volumio[846]: info: sendMpdCommand playlistinfo took 331 milliseconds Oct 05 19:39:11 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:11 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:11 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:11 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:11 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:11 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:11 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:11 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio hostapd[6534]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:11 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio hostapd[6534]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:11 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:11 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:11 volumio hostapd[6534]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:11 volumio hostapd[6534]: nl80211 driver initialization failed. Oct 05 19:39:11 volumio hostapd[6534]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:11 volumio hostapd[6534]: wlan0: AP-DISABLED Oct 05 19:39:11 volumio hostapd[6534]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:11 volumio hostapd[6534]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:11 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:11 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:11 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:11 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:11 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:11 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:11 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:11 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:11 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:12 volumio volumio[846]: info: ------------------------------ 1412ms Oct 05 19:39:12 volumio volumio[846]: info: ------------------------------ 1411ms Oct 05 19:39:12 volumio volumio[846]: info: ------------------------------ 1402ms Oct 05 19:39:12 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:12 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:12 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:12 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:12 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:12 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:12 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:13 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:13 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:13 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:13 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:13 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:13 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:13 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2064. Oct 05 19:39:13 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:13 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:13 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:13 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:13 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:13 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:13 volumio hostapd[6540]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:13 volumio hostapd[6540]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:13 volumio hostapd[6540]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:13 volumio hostapd[6540]: nl80211 driver initialization failed. Oct 05 19:39:13 volumio hostapd[6540]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:13 volumio hostapd[6540]: wlan0: AP-DISABLED Oct 05 19:39:13 volumio hostapd[6540]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:13 volumio hostapd[6540]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:13 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:13 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:14 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:14 volumio volumio[846]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::seek Oct 05 19:39:14 volumio volumio[846]: error: null Oct 05 19:39:14 volumio volumio[846]: info: Oct 05 19:39:14 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:14 volumio volumio[846]: info: Oct 05 19:39:14 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:14 volumio volumio[846]: info: Oct 05 19:39:14 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:14 volumio volumio[846]: info: Oct 05 19:39:14 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand status took 63 milliseconds Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand status took 45 milliseconds Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand status took 35 milliseconds Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand status took 51 milliseconds Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 05 19:39:14 volumio volumio[846]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:14 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:14 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:14 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:14 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:14 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:14 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:14 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:14 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Увезите меня на Дип-хаус","artist":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:14 volumio volumio[846]: verbose: CURRENT POSITION 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:14 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:14 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:15 volumio volumio[846]: info: ------------------------------ 1314ms Oct 05 19:39:15 volumio volumio[846]: info: ------------------------------ 1306ms Oct 05 19:39:15 volumio volumio[846]: info: ------------------------------ 1302ms Oct 05 19:39:15 volumio volumio[846]: info: ------------------------------ 1294ms Oct 05 19:39:15 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:15 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:15 volumio volumio[846]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. Oct 05 19:39:15 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:15 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::stop Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::stPlaybackTimer Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:15 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:15 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:15 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:15 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::serviceStop Oct 05 19:39:15 volumio volumio[846]: info: CorePlayQueue::getTrack 8 Oct 05 19:39:15 volumio volumio[846]: info: CoreCommandRouter::serviceStop Oct 05 19:39:15 volumio volumio[846]: info: ControllerMpd::stop Oct 05 19:39:15 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:15 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:15 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:15 volumio volumio[846]: info: CoreStateMachine::serviceClearAddPlay Oct 05 19:39:15 volumio volumio[846]: info: CoreCommandRouter::serviceClearAddPlayTracks Oct 05 19:39:16 volumio volumio[846]: error: WARNING: No clearAddPlayTracks method for service mpd Oct 05 19:39:16 volumio volumio[846]: info: Oct 05 19:39:16 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand stop took 99 milliseconds Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:16 volumio volumio[846]: info: Oct 05 19:39:16 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:16 volumio volumio[846]: info: Oct 05 19:39:16 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:16 volumio volumio[846]: info: Oct 05 19:39:16 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand status took 71 milliseconds Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand status took 64 milliseconds Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand status took 56 milliseconds Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand status took 46 milliseconds Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 05 19:39:16 volumio volumio[846]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:16 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:16 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:16 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2065. Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:16 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:16 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: No code Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:16 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:39:16 volumio hostapd[6544]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: No code Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio hostapd[6544]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:16 volumio hostapd[6544]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:16 volumio hostapd[6544]: nl80211 driver initialization failed. Oct 05 19:39:16 volumio hostapd[6544]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:16 volumio hostapd[6544]: wlan0: AP-DISABLED Oct 05 19:39:16 volumio hostapd[6544]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:16 volumio hostapd[6544]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:16 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:16 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:16 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:16 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: No code Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"Увезите меня на Дип-хаус","uri":"USB/1/GAYAZOV_BROTHER_-_Uvezite_menya_na_Dip-khaus_66849050.mp3","trackType":"mp3"} Oct 05 19:39:16 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:16 volumio volumio[846]: info: No code Oct 05 19:39:16 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 05 19:39:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 1327ms Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 1311ms Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 1310ms Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 1307ms Oct 05 19:39:17 volumio volumio[846]: info: CoreStateMachine::play index undefined Oct 05 19:39:17 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:17 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:17 volumio volumio[846]: info: CoreStateMachine::startPlaybackTimer Oct 05 19:39:17 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:17 volumio volumio[846]: verbose: ControllerMpd::clearAddPlayTracks USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3 Oct 05 19:39:17 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:17 volumio volumio[846]: info: sendMpdCommand stop took 5 milliseconds Oct 05 19:39:17 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: info: sendMpdCommand clear took 224 milliseconds Oct 05 19:39:17 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand add "USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3" Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: error: updateQueue error: null Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 102ms Oct 05 19:39:17 volumio volumio[846]: info: sendMpdCommand add "USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3" took 92 milliseconds Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 90ms Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 80ms Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 72ms Oct 05 19:39:17 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand play Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:17 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:17 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:17 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:17 volumio volumio[846]: info: Oct 05 19:39:17 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:17 volumio volumio[846]: info: ------------------------------ 244ms Oct 05 19:39:17 volumio volumio[846]: info: sendMpdCommand play took 213 milliseconds Oct 05 19:39:17 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:17 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:18 volumio volumio[846]: info: ------------------------------ 206ms Oct 05 19:39:18 volumio volumio[846]: info: ------------------------------ 112ms Oct 05 19:39:18 volumio volumio[846]: info: ------------------------------ 98ms Oct 05 19:39:18 volumio volumio[846]: info: Oct 05 19:39:18 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:18 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:18 volumio volumio[846]: info: Oct 05 19:39:18 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:18 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:18 volumio volumio[846]: info: Oct 05 19:39:18 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:18 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand status took 67 milliseconds Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand status took 51 milliseconds Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand status took 42 milliseconds Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand status took 72 milliseconds Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:18 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:18 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:18 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:18 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:18 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:18 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:18 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:18 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:18 volumio volumio[846]: info: ------------------------------ 457ms Oct 05 19:39:18 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:18 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2066. Oct 05 19:39:18 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:18 volumio volumio[846]: info: ------------------------------ 766ms Oct 05 19:39:18 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:18 volumio volumio[846]: info: ------------------------------ 806ms Oct 05 19:39:18 volumio volumio[846]: info: sendMpdCommand playlistinfo took 718 milliseconds Oct 05 19:39:18 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:18 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:18 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:18 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:18 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:18 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:18 volumio hostapd[6547]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:19 volumio hostapd[6547]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:19 volumio hostapd[6547]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:19 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:19 volumio hostapd[6547]: nl80211 driver initialization failed. Oct 05 19:39:19 volumio hostapd[6547]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:19 volumio hostapd[6547]: wlan0: AP-DISABLED Oct 05 19:39:19 volumio hostapd[6547]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:19 volumio hostapd[6547]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:19 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:19 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:19 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:19 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:19 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:19 volumio volumio[846]: info: ------------------------------ 1172ms Oct 05 19:39:19 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:19 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:19 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:19 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:19 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:19 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:20 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:20 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:20 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:20 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:20 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:20 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:20 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:20 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:20 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:20 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:20 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:20 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:21 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2067. Oct 05 19:39:21 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:21 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio hostapd[6555]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:21 volumio hostapd[6555]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:21 volumio hostapd[6555]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:21 volumio hostapd[6555]: nl80211 driver initialization failed. Oct 05 19:39:21 volumio hostapd[6555]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:21 volumio hostapd[6555]: wlan0: AP-DISABLED Oct 05 19:39:21 volumio hostapd[6555]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:21 volumio hostapd[6555]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:21 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:21 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:21 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: info: ControllerMpd::seek Oct 05 19:39:21 volumio volumio[846]: error: null Oct 05 19:39:21 volumio volumio[846]: info: Oct 05 19:39:21 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:21 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:21 volumio volumio[846]: info: Oct 05 19:39:21 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:21 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:21 volumio volumio[846]: info: Oct 05 19:39:21 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:21 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:21 volumio volumio[846]: info: Oct 05 19:39:21 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:21 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:21 volumio volumio[846]: info: sendMpdCommand status took 51 milliseconds Oct 05 19:39:21 volumio volumio[846]: info: sendMpdCommand status took 42 milliseconds Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:21 volumio volumio[846]: info: sendMpdCommand status took 57 milliseconds Oct 05 19:39:21 volumio volumio[846]: info: sendMpdCommand status took 52 milliseconds Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:21 volumio volumio[846]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 05 19:39:21 volumio volumio[846]: info: sendMpdCommand playlistinfo took 53 milliseconds Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:21 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:21 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:21 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:21 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:21 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:21 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:21 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:21 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:21 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:22 volumio volumio[846]: info: ------------------------------ 739ms Oct 05 19:39:22 volumio volumio[846]: info: ------------------------------ 740ms Oct 05 19:39:22 volumio volumio[846]: info: sendMpdCommand playlistinfo took 653 milliseconds Oct 05 19:39:22 volumio volumio[846]: info: sendMpdCommand playlistinfo took 654 milliseconds Oct 05 19:39:22 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:22 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:22 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:22 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:22 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:22 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:22 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:22 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:22 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:22 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:22 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:22 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:22 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:22 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:22 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:22 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:22 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:22 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:22 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:22 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:22 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:22 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:22 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:22 volumio volumio[846]: info: ------------------------------ 1318ms Oct 05 19:39:22 volumio volumio[846]: info: ------------------------------ 1311ms Oct 05 19:39:23 volumio volumio[846]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::stPlaybackTimer Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::serviceStop Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::serviceStop Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::stop Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::serviceClearAddPlay Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::serviceClearAddPlayTracks Oct 05 19:39:23 volumio volumio[846]: error: WARNING: No clearAddPlayTracks method for service mpd Oct 05 19:39:23 volumio volumio[846]: info: Oct 05 19:39:23 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand stop took 91 milliseconds Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:23 volumio volumio[846]: info: Oct 05 19:39:23 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:23 volumio volumio[846]: info: Oct 05 19:39:23 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:23 volumio volumio[846]: info: Oct 05 19:39:23 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand status took 45 milliseconds Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand status took 34 milliseconds Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand status took 22 milliseconds Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand status took 14 milliseconds Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 05 19:39:23 volumio volumio[846]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:23 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:23 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: No code Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:23 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2068. Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:23 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:23 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: No code Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:23 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:23 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: No code Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:23 volumio hostapd[6559]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio hostapd[6559]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:23 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:23 volumio hostapd[6559]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:23 volumio hostapd[6559]: nl80211 driver initialization failed. Oct 05 19:39:23 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:23 volumio hostapd[6559]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:23 volumio hostapd[6559]: wlan0: AP-DISABLED Oct 05 19:39:23 volumio hostapd[6559]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:23 volumio hostapd[6559]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:23 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:23 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:23 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:23 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:23 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:23 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:24 volumio volumio[846]: info: No code Oct 05 19:39:24 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:24 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:24 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:24 volumio volumio[846]: info: ------------------------------ 1251ms Oct 05 19:39:24 volumio volumio[846]: info: ------------------------------ 1255ms Oct 05 19:39:24 volumio volumio[846]: info: ------------------------------ 1242ms Oct 05 19:39:24 volumio volumio[846]: info: ------------------------------ 1242ms Oct 05 19:39:24 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:24 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:24 volumio volumio[846]: info: CoreStateMachine::play index undefined Oct 05 19:39:24 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:24 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:24 volumio volumio[846]: info: CoreStateMachine::startPlaybackTimer Oct 05 19:39:24 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:24 volumio volumio[846]: verbose: ControllerMpd::clearAddPlayTracks USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3 Oct 05 19:39:24 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:24 volumio volumio[846]: info: sendMpdCommand stop took 7 milliseconds Oct 05 19:39:24 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 19:39:24 volumio volumio[846]: info: Oct 05 19:39:24 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand clear took 209 milliseconds Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand add "USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3" Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: error: updateQueue error: null Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 101ms Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand add "USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3" took 98 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 90ms Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 63ms Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 59ms Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand play Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:25 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 236ms Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand play took 216 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 213ms Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 108ms Oct 05 19:39:25 volumio volumio[846]: info: ------------------------------ 103ms Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:25 volumio volumio[846]: info: Oct 05 19:39:25 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand status took 93 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand status took 78 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand status took 65 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand status took 54 milliseconds Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand playlistinfo took 85 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand playlistinfo took 65 milliseconds Oct 05 19:39:25 volumio volumio[846]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:25 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:25 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:25 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:25 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:25 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:25 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:25 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:25 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:25 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:25 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:25 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:25 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:26 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:26 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:26 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:26 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:26 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:26 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:26 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:26 volumio volumio[846]: info: ------------------------------ 780ms Oct 05 19:39:26 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:26 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2069. Oct 05 19:39:26 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:26 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:26 volumio hostapd[6562]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:26 volumio hostapd[6562]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:26 volumio hostapd[6562]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:26 volumio hostapd[6562]: nl80211 driver initialization failed. Oct 05 19:39:26 volumio hostapd[6562]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:26 volumio hostapd[6562]: wlan0: AP-DISABLED Oct 05 19:39:26 volumio hostapd[6562]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:26 volumio hostapd[6562]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:26 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:26 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:26 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:26 volumio volumio[846]: info: ------------------------------ 1533ms Oct 05 19:39:26 volumio volumio[846]: info: ------------------------------ 1524ms Oct 05 19:39:26 volumio volumio[846]: info: ------------------------------ 1519ms Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: info: ControllerMpd::seek Oct 05 19:39:27 volumio volumio[846]: error: null Oct 05 19:39:27 volumio volumio[846]: info: Oct 05 19:39:27 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:27 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:27 volumio volumio[846]: info: Oct 05 19:39:27 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:27 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:27 volumio volumio[846]: info: Oct 05 19:39:27 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:27 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:27 volumio volumio[846]: info: Oct 05 19:39:27 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:27 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:27 volumio volumio[846]: info: sendMpdCommand status took 45 milliseconds Oct 05 19:39:27 volumio volumio[846]: info: sendMpdCommand status took 42 milliseconds Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:27 volumio volumio[846]: info: sendMpdCommand status took 45 milliseconds Oct 05 19:39:27 volumio volumio[846]: info: sendMpdCommand status took 45 milliseconds Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:27 volumio volumio[846]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 05 19:39:27 volumio volumio[846]: info: sendMpdCommand playlistinfo took 71 milliseconds Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:27 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:27 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:27 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:27 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:27 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:27 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:27 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:27 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:27 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:27 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:27 volumio volumio[846]: info: ------------------------------ 657ms Oct 05 19:39:28 volumio volumio[846]: info: ------------------------------ 652ms Oct 05 19:39:28 volumio volumio[846]: info: sendMpdCommand playlistinfo took 567 milliseconds Oct 05 19:39:28 volumio volumio[846]: info: sendMpdCommand playlistinfo took 563 milliseconds Oct 05 19:39:28 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:28 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:28 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:28 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:28 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:28 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"МАЛИНОВАЯ ЛАДА","artist":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:28 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:28 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:28 volumio volumio[846]: info: ------------------------------ 1159ms Oct 05 19:39:28 volumio volumio[846]: info: ------------------------------ 1151ms Oct 05 19:39:28 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:28 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2070. Oct 05 19:39:28 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:28 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::stop Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::stPlaybackTimer Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:28 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:28 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:28 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:28 volumio hostapd[6563]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:28 volumio hostapd[6563]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:28 volumio hostapd[6563]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:29 volumio hostapd[6563]: nl80211 driver initialization failed. Oct 05 19:39:29 volumio hostapd[6563]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:29 volumio hostapd[6563]: wlan0: AP-DISABLED Oct 05 19:39:29 volumio hostapd[6563]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:29 volumio hostapd[6563]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::serviceStop Oct 05 19:39:29 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:29 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::serviceStop Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::stop Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:29 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:29 volumio volumio[846]: info: Oct 05 19:39:29 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand stop took 121 milliseconds Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:29 volumio volumio[846]: info: Oct 05 19:39:29 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:29 volumio volumio[846]: info: Oct 05 19:39:29 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:29 volumio volumio[846]: info: Oct 05 19:39:29 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand status took 91 milliseconds Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand status took 80 milliseconds Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand status took 63 milliseconds Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand status took 45 milliseconds Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 05 19:39:29 volumio volumio[846]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:29 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:29 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: No code Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:29 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: No code Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:29 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: No code Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: 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":"GAYAZOV$ BROTHER$","album":"МАЛИНОВАЯ ЛАДА","uri":"USB/1/GAYAZOV_BROTHER_-_MALINOVAYA_LADA_73214200.mp3","trackType":"mp3"} Oct 05 19:39:29 volumio volumio[846]: verbose: CURRENT POSITION 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:29 volumio volumio[846]: info: No code Oct 05 19:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:29 volumio volumio[846]: info: CorePlayQueue::getTrack 3 Oct 05 19:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 1358ms Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 1343ms Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 1334ms Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 1332ms Oct 05 19:39:30 volumio volumio[846]: info: CoreStateMachine::play index undefined Oct 05 19:39:30 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:30 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:30 volumio volumio[846]: info: CoreStateMachine::startPlaybackTimer Oct 05 19:39:30 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:30 volumio volumio[846]: verbose: ControllerMpd::clearAddPlayTracks USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3 Oct 05 19:39:30 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:30 volumio volumio[846]: info: sendMpdCommand stop took 3 milliseconds Oct 05 19:39:30 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 19:39:30 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:30 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: info: sendMpdCommand clear took 204 milliseconds Oct 05 19:39:30 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand add "USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3" Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: error: updateQueue error: null Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 68ms Oct 05 19:39:30 volumio volumio[846]: info: sendMpdCommand add "USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3" took 63 milliseconds Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 61ms Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 52ms Oct 05 19:39:30 volumio volumio[846]: info: ------------------------------ 44ms Oct 05 19:39:30 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand play Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:30 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:30 volumio volumio[846]: info: Oct 05 19:39:30 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:31 volumio volumio[846]: info: ------------------------------ 170ms Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand play took 150 milliseconds Oct 05 19:39:31 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:31 volumio volumio[846]: info: ------------------------------ 150ms Oct 05 19:39:31 volumio volumio[846]: info: ------------------------------ 146ms Oct 05 19:39:31 volumio volumio[846]: info: Oct 05 19:39:31 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:31 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:31 volumio volumio[846]: info: Oct 05 19:39:31 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:31 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:31 volumio volumio[846]: info: ------------------------------ 91ms Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand status took 59 milliseconds Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand status took 41 milliseconds Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:31 volumio volumio[846]: info: Oct 05 19:39:31 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:31 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand status took 79 milliseconds Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand playlistinfo took 66 milliseconds Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:31 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:31 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:31 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:31 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:31 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:31 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2071. Oct 05 19:39:31 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:31 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:31 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:31 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:31 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:31 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:31 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:31 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:31 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:31 volumio volumio[846]: info: ------------------------------ 463ms Oct 05 19:39:31 volumio hostapd[6568]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:31 volumio hostapd[6568]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:31 volumio hostapd[6568]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:31 volumio hostapd[6568]: nl80211 driver initialization failed. Oct 05 19:39:31 volumio hostapd[6568]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:31 volumio hostapd[6568]: wlan0: AP-DISABLED Oct 05 19:39:31 volumio hostapd[6568]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:31 volumio hostapd[6568]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:31 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:31 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:31 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:31 volumio volumio[846]: info: ------------------------------ 608ms Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand status took 523 milliseconds Oct 05 19:39:31 volumio volumio[846]: info: sendMpdCommand playlistinfo took 496 milliseconds Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:31 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:31 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:31 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:31 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:31 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:31 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:31 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:31 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:31 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:31 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:31 volumio volumio[846]: info: ------------------------------ 878ms Oct 05 19:39:32 volumio volumio[846]: info: sendMpdCommand playlistinfo took 426 milliseconds Oct 05 19:39:32 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:32 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:32 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:32 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:32 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:32 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:32 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:32 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:32 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:32 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:32 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:32 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:32 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:32 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:32 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:32 volumio volumio[846]: info: ------------------------------ 1235ms Oct 05 19:39:32 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:32 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:32 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:32 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:32 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:32 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:32 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:32 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:33 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:33 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:33 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:33 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:33 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:33 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:33 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:33 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:33 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:33 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2072. Oct 05 19:39:33 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:33 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:33 volumio hostapd[6572]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:33 volumio hostapd[6572]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:33 volumio hostapd[6572]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:33 volumio hostapd[6572]: nl80211 driver initialization failed. Oct 05 19:39:33 volumio hostapd[6572]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:33 volumio hostapd[6572]: wlan0: AP-DISABLED Oct 05 19:39:33 volumio hostapd[6572]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:33 volumio hostapd[6572]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:33 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:33 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:33 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: ControllerMpd::seek Oct 05 19:39:34 volumio volumio[846]: error: null Oct 05 19:39:34 volumio volumio[846]: info: Oct 05 19:39:34 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:34 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:34 volumio volumio[846]: info: Oct 05 19:39:34 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:34 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:34 volumio volumio[846]: info: Oct 05 19:39:34 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:34 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:34 volumio volumio[846]: info: Oct 05 19:39:34 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:34 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:34 volumio volumio[846]: info: sendMpdCommand status took 52 milliseconds Oct 05 19:39:34 volumio volumio[846]: info: sendMpdCommand status took 54 milliseconds Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:34 volumio volumio[846]: info: sendMpdCommand status took 52 milliseconds Oct 05 19:39:34 volumio volumio[846]: info: sendMpdCommand status took 47 milliseconds Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:34 volumio volumio[846]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 05 19:39:34 volumio volumio[846]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:34 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:34 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:34 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:34 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:34 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:34 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:34 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:34 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:34 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:35 volumio volumio[846]: info: ------------------------------ 711ms Oct 05 19:39:35 volumio volumio[846]: info: ------------------------------ 711ms Oct 05 19:39:35 volumio volumio[846]: info: sendMpdCommand playlistinfo took 625 milliseconds Oct 05 19:39:35 volumio volumio[846]: info: sendMpdCommand playlistinfo took 624 milliseconds Oct 05 19:39:35 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:35 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:35 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:35 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:35 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:35 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:35 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:35 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:35 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:35 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:35 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:35 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:35 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:35 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Саламандра","artist":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:35 volumio volumio[846]: verbose: CURRENT POSITION 7 Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:35 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:35 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:35 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:35 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:35 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:35 volumio volumio[846]: info: ------------------------------ 1435ms Oct 05 19:39:35 volumio volumio[846]: info: ------------------------------ 1428ms Oct 05 19:39:36 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:36 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2073. Oct 05 19:39:36 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:36 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:36 volumio hostapd[6590]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:36 volumio hostapd[6590]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:36 volumio hostapd[6590]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:36 volumio hostapd[6590]: nl80211 driver initialization failed. Oct 05 19:39:36 volumio hostapd[6590]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:36 volumio hostapd[6590]: wlan0: AP-DISABLED Oct 05 19:39:36 volumio hostapd[6590]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:36 volumio hostapd[6590]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:36 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:36 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:36 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::stop Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::stPlaybackTimer Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::serviceStop Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::serviceStop Oct 05 19:39:37 volumio volumio[846]: info: ControllerMpd::stop Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:37 volumio volumio[846]: Upnp client error: Error: This socket has been ended by the other party Oct 05 19:39:37 volumio volumio[846]: info: Oct 05 19:39:37 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand stop took 431 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:37 volumio volumio[846]: info: Oct 05 19:39:37 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:37 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:37 volumio volumio[846]: info: Oct 05 19:39:37 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:37 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:37 volumio volumio[846]: info: Oct 05 19:39:37 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:37 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 7 Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::play index undefined Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 6 Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::startPlaybackTimer Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 6 Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::clearAddPlayTracks USB/1/HammAli_Navai_-_Ptichka_72965451.mp3 Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand status took 114 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand status took 100 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand status took 90 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand status took 81 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand stop took 22 milliseconds Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::updateTrackBlock Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrackBlock Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::serviceClearAddPlay Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::serviceClearAddPlayTracks Oct 05 19:39:37 volumio volumio[846]: error: WARNING: No clearAddPlayTracks method for service mpd Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::serviceClearAddPlay Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::serviceClearAddPlayTracks Oct 05 19:39:37 volumio volumio[846]: error: WARNING: No clearAddPlayTracks method for service mpd Oct 05 19:39:37 volumio volumio[846]: info: Oct 05 19:39:37 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:37 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand playlistinfo took 122 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand playlistinfo took 122 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand playlistinfo took 118 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand playlistinfo took 115 milliseconds Oct 05 19:39:37 volumio volumio[846]: info: sendMpdCommand clear took 114 milliseconds Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:37 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand add "USB/1/HammAli_Navai_-_Ptichka_72965451.mp3" Oct 05 19:39:37 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:37 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:37 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:37 volumio volumio[846]: info: No code Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:37 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:37 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:37 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:37 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:37 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:37 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio volumio[846]: info: No code Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:38 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio volumio[846]: info: No code Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: 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":"MIA BOYKA, T-killah","album":"Саламандра","uri":"USB/1/MIA_BOYKA_T-killah_-_Salamandra_74703971.mp3","trackType":"mp3"} Oct 05 19:39:38 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio volumio[846]: info: No code Oct 05 19:39:38 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:38 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:38 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:38 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:38 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2074. Oct 05 19:39:38 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:38 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:38 volumio volumio[846]: info: ------------------------------ 1334ms Oct 05 19:39:38 volumio hostapd[6613]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:38 volumio volumio[846]: info: ------------------------------ 1326ms Oct 05 19:39:38 volumio hostapd[6613]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:38 volumio volumio[846]: info: ------------------------------ 1315ms Oct 05 19:39:38 volumio volumio[846]: info: ------------------------------ 1310ms Oct 05 19:39:38 volumio hostapd[6613]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:38 volumio hostapd[6613]: nl80211 driver initialization failed. Oct 05 19:39:38 volumio hostapd[6613]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:38 volumio hostapd[6613]: wlan0: AP-DISABLED Oct 05 19:39:38 volumio hostapd[6613]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:38 volumio hostapd[6613]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:38 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:38 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:38 volumio volumio[846]: info: Oct 05 19:39:38 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:38 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:38 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:38 volumio volumio[846]: info: Oct 05 19:39:38 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:38 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:38 volumio volumio[846]: info: Oct 05 19:39:38 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:38 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:39 volumio volumio[846]: error: updateQueue error: null Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:39 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 1206ms Oct 05 19:39:39 volumio volumio[846]: info: sendMpdCommand add "USB/1/HammAli_Navai_-_Ptichka_72965451.mp3" took 1175 milliseconds Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 91ms Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 63ms Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 52ms Oct 05 19:39:39 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand play Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:39 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:39 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces system playlist update Oct 05 19:39:39 volumio volumio[846]: info: Ignoring MPD Status Update Oct 05 19:39:39 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:39 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 850ms Oct 05 19:39:39 volumio volumio[846]: info: sendMpdCommand play took 847 milliseconds Oct 05 19:39:39 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:39 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 847ms Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 743ms Oct 05 19:39:39 volumio volumio[846]: info: ------------------------------ 747ms Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:39 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:39 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:39 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:39 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:39 volumio volumio[846]: info: Oct 05 19:39:39 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 05 19:39:39 volumio volumio[846]: info: ControllerMpd::getState Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand status took 409 milliseconds Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand status took 385 milliseconds Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand status took 352 milliseconds Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand status took 325 milliseconds Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand playlistinfo took 64 milliseconds Oct 05 19:39:40 volumio volumio[846]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:40 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 05 19:39:40 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Птичка","artist":"HammAli & Navai","album":"Птичка","uri":"USB/1/HammAli_Navai_-_Ptichka_72965451.mp3","trackType":"mp3"} Oct 05 19:39:40 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 05 19:39:40 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Птичка","artist":"HammAli & Navai","album":"Птичка","uri":"USB/1/HammAli_Navai_-_Ptichka_72965451.mp3","trackType":"mp3"} Oct 05 19:39:40 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:40 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:40 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Птичка","artist":"HammAli & Navai","album":"Птичка","uri":"USB/1/HammAli_Navai_-_Ptichka_72965451.mp3","trackType":"mp3"} Oct 05 19:39:40 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:40 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:40 volumio volumio[846]: info: ControllerMpd::pushState Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Птичка","artist":"HammAli & Navai","album":"Птичка","uri":"USB/1/HammAli_Navai_-_Ptichka_72965451.mp3","trackType":"mp3"} Oct 05 19:39:40 volumio volumio[846]: verbose: CURRENT POSITION 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 05 19:39:40 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:40 volumio volumio[846]: info: CoreStateMachine::pushState Oct 05 19:39:40 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:40 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 05 19:39:40 volumio volumio[846]: info: ------------------------------ 854ms Oct 05 19:39:41 volumio volumio[846]: info: ------------------------------ 1190ms Oct 05 19:39:41 volumio volumio[846]: info: ------------------------------ 1159ms Oct 05 19:39:41 volumio volumio[846]: info: ------------------------------ 1129ms Oct 05 19:39:41 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:41 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2075. Oct 05 19:39:41 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:41 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:41 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:41 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:41 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:41 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:41 volumio hostapd[6615]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:41 volumio hostapd[6615]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:41 volumio hostapd[6615]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:41 volumio hostapd[6615]: nl80211 driver initialization failed. Oct 05 19:39:41 volumio hostapd[6615]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:41 volumio hostapd[6615]: wlan0: AP-DISABLED Oct 05 19:39:41 volumio hostapd[6615]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:41 volumio hostapd[6615]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:41 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:41 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:41 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:41 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:41 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:41 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:41 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:42 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:42 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:42 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:42 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:42 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:42 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:42 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:42 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:42 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:42 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:42 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:42 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:43 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:43 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:43 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:43 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:43 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:43 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:43 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:43 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:43 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:43 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2076. Oct 05 19:39:43 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:43 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:43 volumio hostapd[6623]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:43 volumio hostapd[6623]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:43 volumio hostapd[6623]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:43 volumio hostapd[6623]: nl80211 driver initialization failed. Oct 05 19:39:43 volumio hostapd[6623]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:43 volumio hostapd[6623]: wlan0: AP-DISABLED Oct 05 19:39:43 volumio hostapd[6623]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:43 volumio hostapd[6623]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:43 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:43 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:43 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:43 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:43 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:44 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:44 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:44 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:44 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:44 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:44 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:44 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:44 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:44 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:44 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:45 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 05 19:39:45 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:45 volumio volumio[846]: info: CoreCommandRouter::volumioPrevious Oct 05 19:39:45 volumio volumio[846]: info: CoreStateMachine::previous Oct 05 19:39:45 volumio volumio[846]: info: CorePlayQueue::getTrack 9 Oct 05 19:39:45 volumio volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 19:39:45 volumio volumio[846]: TypeError: Cannot read property 'service' of undefined Oct 05 19:39:45 volumio volumio[846]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1322:97) Oct 05 19:39:45 volumio volumio[846]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:95:28) Oct 05 19:39:45 volumio volumio[846]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) Oct 05 19:39:45 volumio volumio[846]: at Socket.emit (events.js:315:20) Oct 05 19:39:45 volumio volumio[846]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 05 19:39:45 volumio volumio[846]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 05 19:39:45 volumio volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 19:39:45 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:45 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2077. Oct 05 19:39:45 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:46 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:46 volumio hostapd[6635]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:46 volumio hostapd[6635]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:46 volumio hostapd[6635]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:46 volumio hostapd[6635]: nl80211 driver initialization failed. Oct 05 19:39:46 volumio hostapd[6635]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:46 volumio hostapd[6635]: wlan0: AP-DISABLED Oct 05 19:39:46 volumio hostapd[6635]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:46 volumio hostapd[6635]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:46 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:46 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:46 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:48 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:48 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2078. Oct 05 19:39:48 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:48 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:48 volumio hostapd[6636]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:48 volumio hostapd[6636]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:48 volumio hostapd[6636]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:48 volumio hostapd[6636]: nl80211 driver initialization failed. Oct 05 19:39:48 volumio hostapd[6636]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:48 volumio hostapd[6636]: wlan0: AP-DISABLED Oct 05 19:39:48 volumio hostapd[6636]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:48 volumio hostapd[6636]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:48 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:48 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:48 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:50 volumio sudo[6638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 19:38 Oct 05 19:39:50 volumio sudo[6638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 19:39:50 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Oct 05 19:39:50 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2079. Oct 05 19:39:50 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 05 19:39:51 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 05 19:39:51 volumio hostapd[6640]: Configuration file: /etc/hostapd/hostapd.conf Oct 05 19:39:51 volumio hostapd[6640]: nl80211: Driver does not support authentication/association or connect commands Oct 05 19:39:51 volumio hostapd[6640]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 05 19:39:51 volumio hostapd[6640]: nl80211 driver initialization failed. Oct 05 19:39:51 volumio hostapd[6640]: wlan0: interface state UNINITIALIZED->DISABLED Oct 05 19:39:51 volumio hostapd[6640]: wlan0: AP-DISABLED Oct 05 19:39:51 volumio hostapd[6640]: wlan0: CTRL-EVENT-TERMINATING Oct 05 19:39:51 volumio hostapd[6640]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 05 19:39:51 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Oct 05 19:39:51 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Oct 05 19:39:51 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"