-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sat 2025-04-26 09:44:04 CST. -- Apr 26 09:43:00 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 26 09:43:00 smsl-n100 volumio[1097]: info: CURURI: music-library Apr 26 09:43:00 smsl-n100 volumio[1097]: info: Preload queue cleared Apr 26 09:43:01 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 26 09:43:01 smsl-n100 volumio[1097]: info: CURURI: music-library/USB Apr 26 09:43:01 smsl-n100 volumio[1097]: info: Preload queue cleared Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CURURI: music-library/USB/PKBACK#_001 Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Preload queue cleared Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Preloading song: music-library/USB/PKBACK#_001/1_正弦波.wav Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Exploding uri music-library/USB/PKBACK#_001/1_正弦波.wav in service mpd Apr 26 09:43:02 smsl-n100 volumio[1097]: info: ALBUMART /albumart?cacheid=269&web=%CE%B4%D6%AA%3F%3F%3F%3F%3F%3F/%CE%B4%D6%AA%3F%3F%C6%AC%3F%3F%20(2011%2F6%2F26%2016%3A15%3A04)/extralarge&path=%2Fmnt%2FUSB%2FPKBACK%23_001%2F1_%E6%AD%A3%E5%BC%A6%E6%B3%A2.wav&metadata=false Apr 26 09:43:02 smsl-n100 volumio[1097]: info: URI /mnt/USB/PKBACK#_001/1_正弦波.wav Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Preload queue cleared Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::ClearQueue Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::stop Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::updateTrackBlock Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrackBlock Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::stPlaybackTimer Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 1 Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::serviceStop Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 1 Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::serviceStop Apr 26 09:43:02 smsl-n100 volumio[1097]: info: ControllerMpd::stop Apr 26 09:43:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::clearPlayQueue Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::saveQueue Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushQueue Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::addQueueItems Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::addQueueItems Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Preload queue cleared Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Adding Item to queue: music-library/USB/PKBACK#_001/1_正弦波.wav Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Using cached record of: music-library/USB/PKBACK#_001/1_正弦波.wav Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushQueue Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::saveQueue Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::updateTrackBlock Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrackBlock Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPlay Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::play index 0 Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::stop Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::play index undefined Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreStateMachine::startPlaybackTimer Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 09:43:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 26 09:43:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::clearAddPlayTracks USB/PKBACK#_001/1_正弦波.wav Apr 26 09:43:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:02 smsl-n100 volumio[1097]: info: sendMpdCommand stop took 94 milliseconds Apr 26 09:43:02 smsl-n100 volumio[1097]: info: sendMpdCommand stop took 29 milliseconds Apr 26 09:43:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: sendMpdCommand clear took 9 milliseconds Apr 26 09:43:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand add "USB/PKBACK#_001/1_正弦波.wav" Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:02 smsl-n100 volumio[1097]: error: updateQueue error: null Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: ------------------------------ 29ms Apr 26 09:43:02 smsl-n100 volumio[1097]: info: sendMpdCommand add "USB/PKBACK#_001/1_正弦波.wav" took 26 milliseconds Apr 26 09:43:02 smsl-n100 volumio[1097]: info: ------------------------------ 21ms Apr 26 09:43:02 smsl-n100 volumio[1097]: info: ------------------------------ 18ms Apr 26 09:43:02 smsl-n100 volumio[1097]: info: ------------------------------ 15ms Apr 26 09:43:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand play Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Apr 26 09:43:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update Apr 26 09:43:02 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 36ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand play took 28 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 25ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 20ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 16ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 53 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 26 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 19 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 14 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Apr 26 09:43:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 42 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 24 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 20 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 15 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus stop Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 236ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 302ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 281ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 275ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 209 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 203 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 203 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 202 milliseconds Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"} Apr 26 09:43:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 525ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 501ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 497ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: ------------------------------ 493ms Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:03 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:03 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 26 09:43:03 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:03 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 26 09:43:04 smsl-n100 volumio[1097]: info: VolumeController::SetAlsaVolume0 Apr 26 09:43:04 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:04 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:04 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:04 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:04 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:04 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:04 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:06 smsl-n100 volumio[1097]: info: Button was pressed for 0.153 seconds, starting shutdown Apr 26 09:43:06 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:10 smsl-n100 volumio[1097]: info: Button was pressed for 0.203 seconds, starting shutdown Apr 26 09:43:10 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:10 smsl-n100 volumio[1097]: info: Button was pressed for 0.168 seconds, starting shutdown Apr 26 09:43:10 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:10 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:10 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:10 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:10 smsl-n100 sudo[3360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:10 smsl-n100 sudo[3360]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:10 smsl-n100 sudo[3360]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:10 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:11 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:11 smsl-n100 sudo[3363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:11 smsl-n100 sudo[3363]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:11 smsl-n100 systemd[1]: Stopping Wireless Services... Apr 26 09:43:11 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:11 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:11 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:11 smsl-n100 wpa_supplicant[2497]: wlan0: CTRL-EVENT-DISCONNECTED bssid=84:f5:eb:f9:23:31 reason=3 locally_generated=1 Apr 26 09:43:11 smsl-n100 dhcpcd[961]: wlan0: carrier lost Apr 26 09:43:11 smsl-n100 sudo[3366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:11 smsl-n100 wpa_supplicant[2497]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Apr 26 09:43:11 smsl-n100 sudo[3366]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:11 smsl-n100 sudo[3366]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:11 smsl-n100 avahi-daemon[586]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 26 09:43:11 smsl-n100 avahi-daemon[586]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.86. Apr 26 09:43:11 smsl-n100 wpa_supplicant[2497]: wlan0: CTRL-EVENT-TERMINATING Apr 26 09:43:11 smsl-n100 volumio[1097]: info: Discovery: A device disappeared from network Apr 26 09:43:11 smsl-n100 avahi-daemon[586]: Withdrawing address record for 192.168.1.86 on wlan0. Apr 26 09:43:11 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:43:11 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:43:11 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:43:11 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:11 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:11 smsl-n100 dhcpcd[961]: wlan0: deleting route to 192.168.0.0/23 Apr 26 09:43:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:11 smsl-n100 dhcpcd[961]: wlan0: deleting default route via 192.168.1.1 Apr 26 09:43:11 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:11 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:11 smsl-n100 sudo[3384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:11 smsl-n100 sudo[3384]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:11 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:11 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 26 09:43:11 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:43:11 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:43:11 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:11 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:11 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:43:11 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:12 smsl-n100 volumio[1097]: info: Discovery: A device disappeared from network Apr 26 09:43:12 smsl-n100 volumio[1097]: info: Button was pressed for 2.151 seconds, starting shutdown Apr 26 09:43:12 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:12 smsl-n100 wireless.js[3408]: Cleaning previous... Apr 26 09:43:12 smsl-n100 sudo[3423]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 26 09:43:12 smsl-n100 sudo[3423]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:12 smsl-n100 sudo[3423]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:12 smsl-n100 sudo[3425]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 26 09:43:12 smsl-n100 sudo[3425]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:12 smsl-n100 sudo[3425]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:12 smsl-n100 ntpd[2638]: Deleting interface #4 wlan0, 192.168.1.86#123, interface stats: received=0, sent=0, dropped=0, active_time=78 secs Apr 26 09:43:12 smsl-n100 wireless.js[3408]: Stopped aP Apr 26 09:43:12 smsl-n100 sudo[3432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 09:43:12 smsl-n100 sudo[3432]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:12 smsl-n100 dhcpcd[961]: wlan0: carrier acquired Apr 26 09:43:12 smsl-n100 sudo[3432]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:12 smsl-n100 dhcpcd[961]: wlan0: IAID 2b:ee:c9:1c Apr 26 09:43:12 smsl-n100 sudo[3435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 26 09:43:12 smsl-n100 sudo[3435]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:12 smsl-n100 sudo[3435]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:13 smsl-n100 sudo[3442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 09:43:13 smsl-n100 sudo[3442]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:13 smsl-n100 sudo[3442]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:13 smsl-n100 sudo[3444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 26 09:43:13 smsl-n100 sudo[3444]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:13 smsl-n100 dhcpcd[961]: wlan0: soliciting an IPv6 router Apr 26 09:43:13 smsl-n100 volumio[1097]: info: Button was pressed for 0.185 seconds, starting shutdown Apr 26 09:43:13 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:13 smsl-n100 volumio[1097]: info: Button was pressed for 0.188 seconds, starting shutdown Apr 26 09:43:13 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:13 smsl-n100 dhcpcd[961]: wlan0: rebinding lease of 192.168.1.86 Apr 26 09:43:14 smsl-n100 volumio[1097]: info: Button was pressed for 0.163 seconds, starting shutdown Apr 26 09:43:14 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:14 smsl-n100 volumio[1097]: info: Button was pressed for 0.348 seconds, starting shutdown Apr 26 09:43:14 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:14 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:14 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:14 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Button was pressed for 0.236 seconds, starting shutdown Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:15 smsl-n100 sudo[3449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:15 smsl-n100 sudo[3449]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Button was pressed for 0.271 seconds, starting shutdown Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:15 smsl-n100 sudo[3449]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:15 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:15 smsl-n100 sudo[3452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:15 smsl-n100 sudo[3452]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:15 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:15 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 26 09:43:15 smsl-n100 systemd[1]: wireless.service: Killing process 3441 (sh) with signal SIGKILL. Apr 26 09:43:15 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:15 smsl-n100 systemd[1]: wireless.service: Killing process 3444 (sudo) with signal SIGKILL. Apr 26 09:43:15 smsl-n100 systemd[1]: wireless.service: Killing process 3445 (grep) with signal SIGKILL. Apr 26 09:43:15 smsl-n100 systemd[1]: wireless.service: Killing process 3446 (cut) with signal SIGKILL. Apr 26 09:43:15 smsl-n100 systemd[1]: wireless.service: Killing process 3447 (iw) with signal SIGKILL. Apr 26 09:43:15 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:43:15 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:43:15 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:15 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:43:15 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Button was pressed for 0.122 seconds, starting shutdown Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Button was pressed for 0.12 seconds, starting shutdown Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Button was pressed for 0.123 seconds, starting shutdown Apr 26 09:43:15 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:16 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:16 smsl-n100 wireless.js[3454]: Cleaning previous... Apr 26 09:43:16 smsl-n100 sudo[3469]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 26 09:43:16 smsl-n100 sudo[3469]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:16 smsl-n100 sudo[3469]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:16 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:16 smsl-n100 sudo[3471]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 26 09:43:16 smsl-n100 sudo[3471]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:16 smsl-n100 dhcpcd[961]: wlan0: carrier lost Apr 26 09:43:16 smsl-n100 sudo[3471]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:16 smsl-n100 wireless.js[3454]: Stopped aP Apr 26 09:43:16 smsl-n100 sudo[3507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 09:43:16 smsl-n100 sudo[3507]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:16 smsl-n100 sudo[3507]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:16 smsl-n100 sudo[3509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 26 09:43:16 smsl-n100 sudo[3509]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:16 smsl-n100 sudo[3509]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:16 smsl-n100 sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 09:43:16 smsl-n100 sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:16 smsl-n100 sudo[3516]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:16 smsl-n100 sudo[3518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 26 09:43:16 smsl-n100 sudo[3518]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:17 smsl-n100 volumio[1097]: info: Discovery: adding 3731a682-cd94-47e0-8dbe-c6ba0ff31cd4 Apr 26 09:43:17 smsl-n100 volumio[1097]: info: Discovery: Found device SMSL N100 Apr 26 09:43:17 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetState Apr 26 09:43:17 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:17 smsl-n100 dhcpcd[961]: wlan0: carrier acquired Apr 26 09:43:17 smsl-n100 dhcpcd[961]: wlan0: IAID 2b:ee:c9:1c Apr 26 09:43:17 smsl-n100 volumio[1097]: info: Reporting MCU Network Status: 1 Apr 26 09:43:17 smsl-n100 volumio[1097]: info: Volumio Network Manager: Network status updated: 1 Apr 26 09:43:17 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:17 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:17 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:17 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:17 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:17 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:17 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:17 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 26 09:43:17 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:17 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 26 09:43:17 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 1, message: undefined Apr 26 09:43:18 smsl-n100 dhcpcd[961]: wlan0: soliciting an IPv6 router Apr 26 09:43:18 smsl-n100 volumio[1097]: info: VolumeController::SetAlsaVolume0 Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:18 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:18 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:18 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:18 smsl-n100 dhcpcd[961]: wlan0: rebinding lease of 192.168.1.86 Apr 26 09:43:18 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:18 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:18 smsl-n100 sudo[3542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:18 smsl-n100 sudo[3542]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:18 smsl-n100 sudo[3542]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:18 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:18 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:18 smsl-n100 sudo[3545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:18 smsl-n100 sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:18 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:18 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 26 09:43:18 smsl-n100 systemd[1]: wireless.service: Killing process 3515 (sh) with signal SIGKILL. Apr 26 09:43:18 smsl-n100 systemd[1]: wireless.service: Killing process 3518 (sudo) with signal SIGKILL. Apr 26 09:43:18 smsl-n100 systemd[1]: wireless.service: Killing process 3519 (grep) with signal SIGKILL. Apr 26 09:43:18 smsl-n100 systemd[1]: wireless.service: Killing process 3520 (cut) with signal SIGKILL. Apr 26 09:43:18 smsl-n100 systemd[1]: wireless.service: Killing process 3521 (iw) with signal SIGKILL. Apr 26 09:43:18 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:43:18 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:43:18 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:18 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:18 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:43:18 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:18 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:18 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:18 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:18 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:18 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:19 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:19 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:19 smsl-n100 sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:19 smsl-n100 sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:19 smsl-n100 sudo[3554]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:19 smsl-n100 sudo[3556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:19 smsl-n100 sudo[3556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:19 smsl-n100 sudo[3556]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:19 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:19 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:19 smsl-n100 sudo[3560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:19 smsl-n100 sudo[3560]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:19 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:19 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:19 smsl-n100 sudo[3560]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:19 smsl-n100 sudo[3563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:19 smsl-n100 sudo[3563]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:19 smsl-n100 sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:19 smsl-n100 sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:19 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:19 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:43:19 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:19 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:43:19 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:19 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:19 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:43:19 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:19 smsl-n100 sudo[3570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:19 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:19 smsl-n100 sudo[3570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:43:19 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:43:19 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Start request repeated too quickly. Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Failed with result 'start-limit-hit'. Apr 26 09:43:19 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:19 smsl-n100 systemd[1]: Failed to start Wireless Services. Apr 26 09:43:19 smsl-n100 sudo[3452]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 sudo[3563]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 sudo[3363]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 sudo[3384]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 sudo[3566]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 sudo[3545]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Start request repeated too quickly. Apr 26 09:43:19 smsl-n100 systemd[1]: wireless.service: Failed with result 'start-limit-hit'. Apr 26 09:43:19 smsl-n100 systemd[1]: Failed to start Wireless Services. Apr 26 09:43:19 smsl-n100 sudo[3570]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:20 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:20 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:20 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:20 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:20 smsl-n100 sudo[3575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:20 smsl-n100 sudo[3575]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:20 smsl-n100 sudo[3575]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:20 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:20 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:20 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:20 smsl-n100 sudo[3578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:20 smsl-n100 sudo[3578]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:20 smsl-n100 systemd[1]: wireless.service: Start request repeated too quickly. Apr 26 09:43:20 smsl-n100 systemd[1]: wireless.service: Failed with result 'start-limit-hit'. Apr 26 09:43:20 smsl-n100 systemd[1]: Failed to start Wireless Services. Apr 26 09:43:20 smsl-n100 sudo[3578]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:23 smsl-n100 dhcpcd[961]: wlan0: probing for an IPv4LL address Apr 26 09:43:23 smsl-n100 dhcpcd[961]: wlan0: DHCP lease expired Apr 26 09:43:23 smsl-n100 dhcpcd[961]: wlan0: soliciting a DHCP lease Apr 26 09:43:23 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:24 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:24 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:24 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:25 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:28 smsl-n100 dhcpcd[961]: wlan0: using IPv4LL address 169.254.233.247 Apr 26 09:43:28 smsl-n100 dhcpcd[961]: wlan0: adding route to 169.254.0.0/16 Apr 26 09:43:28 smsl-n100 avahi-daemon[586]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.233.247. Apr 26 09:43:28 smsl-n100 avahi-daemon[586]: New relevant interface wlan0.IPv4 for mDNS. Apr 26 09:43:28 smsl-n100 avahi-daemon[586]: Registering new address record for 169.254.233.247 on wlan0.IPv4. Apr 26 09:43:30 smsl-n100 volumio[1097]: info: Discovery: this is already registered, 3731a682-cd94-47e0-8dbe-c6ba0ff31cd4 Apr 26 09:43:30 smsl-n100 volumio[1097]: info: Discovery: Found device SMSL N100 Apr 26 09:43:30 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetState Apr 26 09:43:30 smsl-n100 ntpd[2638]: Listen normally on 5 wlan0 169.254.233.247:123 Apr 26 09:43:30 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:30 smsl-n100 ntpd[2638]: new interface(s) found: waking up resolver Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 09:43:34 smsl-n100 volumio[1097]: xcb_connection_has_error() returned true Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 09:43:34 smsl-n100 volumio[1097]: xcb_connection_has_error() returned true Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 09:43:34 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 09:43:38 smsl-n100 volumio[1097]: info: Reporting MCU Network Status: 3 Apr 26 09:43:38 smsl-n100 volumio[1097]: info: Volumio Network Manager: Network status updated: 3 Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:38 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:38 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:38 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:38 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 26 09:43:38 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:38 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 26 09:43:38 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 3, message: undefined Apr 26 09:43:38 smsl-n100 volumio[1097]: info: VolumeController::SetAlsaVolume0 Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:38 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:38 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:38 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 09:43:38 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 26 09:43:38 smsl-n100 sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 26 09:43:38 smsl-n100 sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:38 smsl-n100 sudo[3663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 09:43:38 smsl-n100 sudo[3658]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:38 smsl-n100 sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:38 smsl-n100 sudo[3663]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:38 smsl-n100 sudo[3672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 09:43:38 smsl-n100 sudo[3672]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:38 smsl-n100 sudo[3672]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:38 smsl-n100 sudo[3677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 26 09:43:38 smsl-n100 sudo[3677]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:38 smsl-n100 sudo[3677]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:39 smsl-n100 sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 09:43:39 smsl-n100 sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:39 smsl-n100 sudo[3684]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 26 09:43:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 26 09:43:39 smsl-n100 sudo[3687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 09:43:39 smsl-n100 sudo[3687]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:39 smsl-n100 sudo[3687]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:39 smsl-n100 sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 09:43:39 smsl-n100 sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:44 smsl-n100 sudo[3690]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:49 smsl-n100 volumio[1097]: info: Button was pressed for 0.211 seconds, starting shutdown Apr 26 09:43:49 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:49 smsl-n100 volumio[1097]: info: Button was pressed for 0 seconds, starting shutdown Apr 26 09:43:49 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:49 smsl-n100 volumio[1097]: info: Button was pressed for 0.19 seconds, starting shutdown Apr 26 09:43:49 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:54 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:54 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:54 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:43:54 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:43:54 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:54 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:43:54 smsl-n100 sudo[3711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:54 smsl-n100 sudo[3711]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:54 smsl-n100 sudo[3711]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:54 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:54 smsl-n100 sudo[3714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:43:54 smsl-n100 sudo[3714]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:54 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:54 smsl-n100 sudo[3714]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:54 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:54 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:43:54 smsl-n100 sudo[3717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:54 smsl-n100 sudo[3717]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:54 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:43:54 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:43:54 smsl-n100 sudo[3720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:43:54 smsl-n100 sudo[3720]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:54 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:43:54 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:55 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:55 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 26 09:43:55 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:43:55 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:43:55 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:55 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 09:43:55 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:43:55 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:43:56 smsl-n100 wireless.js[3723]: Cleaning previous... Apr 26 09:43:56 smsl-n100 sudo[3775]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 26 09:43:56 smsl-n100 sudo[3775]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:56 smsl-n100 avahi-daemon[586]: Withdrawing address record for 169.254.233.247 on wlan0. Apr 26 09:43:56 smsl-n100 avahi-daemon[586]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.233.247. Apr 26 09:43:56 smsl-n100 avahi-daemon[586]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 26 09:43:56 smsl-n100 sudo[3775]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:56 smsl-n100 volumio[1097]: info: Discovery: A device disappeared from network Apr 26 09:43:56 smsl-n100 sudo[3777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 26 09:43:56 smsl-n100 sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:56 smsl-n100 dhcpcd[961]: wlan0: carrier lost Apr 26 09:43:56 smsl-n100 sudo[3777]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.386097, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 09:43:56 smsl-n100 nmbd[752]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.387090, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 09:43:56 smsl-n100 nmbd[752]: retransmit_or_expire_response_records: Failed to resend packet id 17825 to IP 169.254.255.255 on subnet 169.254.233.247 Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.387542, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 09:43:56 smsl-n100 nmbd[752]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.388171, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 09:43:56 smsl-n100 nmbd[752]: retransmit_or_expire_response_records: Failed to resend packet id 17826 to IP 169.254.255.255 on subnet 169.254.233.247 Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.388579, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 09:43:56 smsl-n100 nmbd[752]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.389112, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 09:43:56 smsl-n100 nmbd[752]: retransmit_or_expire_response_records: Failed to resend packet id 17827 to IP 169.254.255.255 on subnet 169.254.233.247 Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.389407, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 09:43:56 smsl-n100 nmbd[752]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.389676, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 09:43:56 smsl-n100 nmbd[752]: retransmit_or_expire_response_records: Failed to resend packet id 17828 to IP 169.254.255.255 on subnet 169.254.233.247 Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.390137, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 09:43:56 smsl-n100 nmbd[752]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 09:43:56 smsl-n100 nmbd[752]: [2025/04/26 09:43:56.390510, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 09:43:56 smsl-n100 nmbd[752]: retransmit_or_expire_response_records: Failed to resend packet id 17829 to IP 169.254.255.255 on subnet 169.254.233.247 Apr 26 09:43:56 smsl-n100 dhcpcd[961]: wlan0: deleting route to 169.254.0.0/16 Apr 26 09:43:56 smsl-n100 wireless.js[3723]: Stopped aP Apr 26 09:43:56 smsl-n100 sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 09:43:56 smsl-n100 sudo[3812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:56 smsl-n100 sudo[3812]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:56 smsl-n100 dhcpcd[961]: wlan0: carrier acquired Apr 26 09:43:56 smsl-n100 sudo[3823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 26 09:43:56 smsl-n100 sudo[3823]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:56 smsl-n100 dhcpcd[961]: wlan0: IAID 2b:ee:c9:1c Apr 26 09:43:56 smsl-n100 sudo[3823]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:56 smsl-n100 sudo[3836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 26 09:43:56 smsl-n100 sudo[3836]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:56 smsl-n100 sudo[3836]: pam_unix(sudo:session): session closed for user root Apr 26 09:43:56 smsl-n100 sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 26 09:43:56 smsl-n100 sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:43:57 smsl-n100 dhcpcd[961]: wlan0: soliciting an IPv6 router Apr 26 09:43:57 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:57 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 1 Apr 26 09:43:57 smsl-n100 dhcpcd[961]: wlan0: soliciting a DHCP lease Apr 26 09:43:57 smsl-n100 ntpd[2638]: Deleting interface #5 wlan0, 169.254.233.247#123, interface stats: received=0, sent=0, dropped=0, active_time=27 secs Apr 26 09:43:57 smsl-n100 volumio[1097]: info: Button was pressed for 0.197 seconds, starting shutdown Apr 26 09:43:57 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:57 smsl-n100 volumio[1097]: info: Button was pressed for 0.39 seconds, starting shutdown Apr 26 09:43:57 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:58 smsl-n100 volumio[1097]: info: Button was pressed for 0.184 seconds, starting shutdown Apr 26 09:43:58 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:58 smsl-n100 volumio[1097]: info: Reporting MCU Network Status: 1 Apr 26 09:43:58 smsl-n100 volumio[1097]: info: Volumio Network Manager: Network status updated: 1 Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 26 09:43:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:43:58 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 26 09:43:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 1, message: undefined Apr 26 09:43:58 smsl-n100 volumio[1097]: info: VolumeController::SetAlsaVolume0 Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:43:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:43:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:43:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:43:58 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:43:58 smsl-n100 volumio[1097]: info: Button was pressed for 0.236 seconds, starting shutdown Apr 26 09:43:58 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Button was pressed for 0.181 seconds, starting shutdown Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Button was pressed for 0.256 seconds, starting shutdown Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Button was pressed for 0.747 seconds, starting shutdown Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Signaling System Off Apr 26 09:43:59 smsl-n100 volumio[1097]: info: Discovery: Started advertising with name: SMSL N100 Apr 26 09:44:02 smsl-n100 sudo[3838]: pam_unix(sudo:session): session closed for user root Apr 26 09:44:02 smsl-n100 wireless.js[3723]: Wireless networking forced to hotspot mode Apr 26 09:44:02 smsl-n100 sudo[3864]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 26 09:44:02 smsl-n100 sudo[3864]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:44:02 smsl-n100 sudo[3864]: pam_unix(sudo:session): session closed for user root Apr 26 09:44:02 smsl-n100 sudo[3866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 26 09:44:02 smsl-n100 sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:44:02 smsl-n100 dhcpcd[961]: wlan0: carrier lost Apr 26 09:44:02 smsl-n100 sudo[3866]: pam_unix(sudo:session): session closed for user root Apr 26 09:44:02 smsl-n100 wireless.js[3723]: Starting Force Hotspot Apr 26 09:44:02 smsl-n100 avahi-daemon[586]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 26 09:44:02 smsl-n100 avahi-daemon[586]: New relevant interface wlan0.IPv4 for mDNS. Apr 26 09:44:02 smsl-n100 avahi-daemon[586]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 26 09:44:02 smsl-n100 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 26 09:44:02 smsl-n100 volumio[1097]: info: Button was pressed for 5 seconds, starting hotspot Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Apr 26 09:44:02 smsl-n100 volumio[1097]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 09:44:02 smsl-n100 volumio[1097]: error: Forcing Hotspot mode Apr 26 09:44:02 smsl-n100 dhcpcd[961]: wlan0: carrier acquired Apr 26 09:44:02 smsl-n100 hostapd[3890]: Configuration file: /etc/hostapd/hostapd.conf Apr 26 09:44:02 smsl-n100 sudo[3898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 26 09:44:02 smsl-n100 sudo[3898]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:44:02 smsl-n100 dhcpcd[961]: wlan0: IAID 2b:ee:c9:1c Apr 26 09:44:02 smsl-n100 sudo[3898]: pam_unix(sudo:session): session closed for user root Apr 26 09:44:02 smsl-n100 volumio[1097]: info: Permissions for /etc/hostapd/hostapd.conf Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 26 09:44:02 smsl-n100 volumio[1097]: info: Discovery: Restarting Advertising due to device name change Apr 26 09:44:02 smsl-n100 volumio[1097]: info: Apr 26 09:44:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:44:02 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:44:02 smsl-n100 volumio[1097]: info: Apr 26 09:44:02 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:44:02 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:44:02 smsl-n100 volumio[1097]: info: sendMpdCommand status took 10 milliseconds Apr 26 09:44:02 smsl-n100 volumio[1097]: info: sendMpdCommand status took 5 milliseconds Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService stop Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::stPlaybackTimer Apr 26 09:44:02 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 26 09:44:02 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService stop Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus stop Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:02 smsl-n100 volumio[1097]: info: No code Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:02 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:02 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ------------------------------ 289ms Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ------------------------------ 285ms Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Apr 26 09:44:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Apr 26 09:44:03 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ControllerMpd::getState Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Apr 26 09:44:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 10 milliseconds Apr 26 09:44:03 smsl-n100 volumio[1097]: info: sendMpdCommand status took 6 milliseconds Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService stop Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus stop Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: info: No code Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ControllerMpd::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 26 09:44:03 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService stop Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus stop Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: info: No code Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ------------------------------ 159ms Apr 26 09:44:03 smsl-n100 volumio[1097]: info: ------------------------------ 156ms Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output Apr 26 09:44:03 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:44:03 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 26 09:44:03 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined Apr 26 09:44:03 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Discovery: adding 3731a682-cd94-47e0-8dbe-c6ba0ff31cd4 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: Discovery: Found device SMSL N100 Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetState Apr 26 09:44:03 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 0 Apr 26 09:44:03 smsl-n100 volumio[1097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 09:44:03 smsl-n100 volumio[1097]: Error: The value NaN is not a number Apr 26 09:44:03 smsl-n100 volumio[1097]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 26 09:44:03 smsl-n100 volumio[1097]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 26 09:44:03 smsl-n100 volumio[1097]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 26 09:44:03 smsl-n100 volumio[1097]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 26 09:44:03 smsl-n100 volumio[1097]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 26 09:44:03 smsl-n100 volumio[1097]: at Browser.emit (events.js:400:28) Apr 26 09:44:03 smsl-n100 volumio[1097]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 26 09:44:03 smsl-n100 volumio[1097]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 26 09:44:03 smsl-n100 volumio[1097]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 26 09:44:03 smsl-n100 volumio[1097]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 26 09:44:03 smsl-n100 volumio[1097]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 26 09:44:03 smsl-n100 volumio[1097]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 26 09:44:03 smsl-n100 volumio[1097]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 26 09:44:03 smsl-n100 volumio[1097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 09:44:03 smsl-n100 hostapd[3890]: Using interface wlan0 with hwaddr 28:f5:2b:ee:c9:1c and ssid "Volumio-D8FC0" Apr 26 09:44:03 smsl-n100 hostapd[3890]: wlan0: interface state UNINITIALIZED->ENABLED Apr 26 09:44:03 smsl-n100 hostapd[3890]: wlan0: AP-ENABLED Apr 26 09:44:03 smsl-n100 ntpd[2638]: Listen normally on 6 wlan0 192.168.211.1:123 Apr 26 09:44:03 smsl-n100 ntpd[2638]: new interface(s) found: waking up resolver Apr 26 09:44:03 smsl-n100 systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory Apr 26 09:44:03 smsl-n100 sudo[3907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 26 09:44:03 smsl-n100 sudo[3907]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 09:44:03 smsl-n100 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 26 09:44:03 smsl-n100 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Apr 26 09:44:03 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 26 09:44:03 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 26 09:44:03 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 26 09:44:03 smsl-n100 dnsmasq[3919]: dnsmasq: syntax check OK. Apr 26 09:44:04 smsl-n100 dhcpcd[961]: wlan0: soliciting an IPv6 router Apr 26 09:44:04 smsl-n100 systemd[1]: Starting Wireless Services... Apr 26 09:44:04 smsl-n100 dnsmasq[3932]: started, version 2.80 cachesize 150 Apr 26 09:44:04 smsl-n100 dnsmasq[3932]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Apr 26 09:44:04 smsl-n100 dnsmasq-dhcp[3932]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Apr 26 09:44:04 smsl-n100 dnsmasq[3932]: reading /etc/resolv.conf Apr 26 09:44:04 smsl-n100 dnsmasq[3932]: using nameserver 192.168.1.1#53 Apr 26 09:44:04 smsl-n100 dnsmasq[3932]: using nameserver 208.67.222.222#53 Apr 26 09:44:04 smsl-n100 dnsmasq[3932]: using nameserver 208.67.220.220#53 Apr 26 09:44:04 smsl-n100 dnsmasq[3932]: read /etc/hosts - 5 addresses Apr 26 09:44:04 smsl-n100 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Apr 26 09:44:04 smsl-n100 systemd[1]: Reached target Host and Network Name Lookups. Apr 26 09:44:04 smsl-n100 dhcpcd[961]: wlan0: soliciting a DHCP lease Apr 26 09:44:04 smsl-n100 sudo[3940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 09:43 Apr 26 09:44:04 smsl-n100 sudo[3940]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"