-- Logs begin at Sun 2024-11-24 14:17:07 KST, end at Sun 2024-11-24 14:20:46 KST. -- Nov 24 14:19:01 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 24 14:19:01 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:19:02 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:19:02 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::stop Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:02 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Nov 24 14:19:02 volumio volumio[1234]: info: CorePlayQueue::saveQueue Nov 24 14:19:02 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Nov 24 14:19:02 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Nov 24 14:19:02 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:19:02 volumio volumio[1234]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s250321 Nov 24 14:19:02 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 24 14:19:02 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Nov 24 14:19:02 volumio volumio[1234]: info: CorePlayQueue::saveQueue Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Nov 24 14:19:02 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Nov 24 14:19:02 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::play index 0 Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::stop Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::play index undefined Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:02 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:02 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Nov 24 14:19:02 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:02 volumio volumio[1234]: info: [1732425542400] ControllerWebradio::clearAddPlayTrack Nov 24 14:19:02 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 14:19:02 volumio volumio[1234]: info: sendMpdCommand stop took 1 milliseconds Nov 24 14:19:02 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand clear Nov 24 14:19:02 volumio volumio[1234]: info: Nov 24 14:19:02 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:02 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:02 volumio volumio[1234]: info: sendMpdCommand clear took 1 milliseconds Nov 24 14:19:02 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand load "http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3" Nov 24 14:19:02 volumio volumio[1234]: info: Nov 24 14:19:02 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:02 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:02 volumio volumio[1234]: info: Nov 24 14:19:02 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:02 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:03 volumio volumio[1234]: error: updateQueue error: null Nov 24 14:19:03 volumio volumio[1234]: error: updateQueue error: null Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 688ms Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand add "http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3" Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 687ms Nov 24 14:19:03 volumio volumio[1234]: error: updateQueue error: null Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 688ms Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand add "http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3" took 1 milliseconds Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand play Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:03 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:03 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:03 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand play took 3 milliseconds Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 2ms Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 3ms Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 2ms Nov 24 14:19:03 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::ClearQueue Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::stop Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::clearPlayQueue Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::saveQueue Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::addQueueItems Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::addQueueItems Nov 24 14:19:03 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:19:03 volumio volumio[1234]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s250321 Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::volumioPushQueue Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::saveQueue Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::updateTrackBlock Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::getTrackBlock Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::volumioPlay Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::play index 0 Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::stop Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::play index undefined Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::startPlaybackTimer Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:03 volumio volumio[1234]: info: [1732425543389] ControllerWebradio::clearAddPlayTrack Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces state update: player Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand stop took 2 milliseconds Nov 24 14:19:03 volumio volumio[1234]: info: ControllerMpd::getState Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand clear Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces state update: player Nov 24 14:19:03 volumio volumio[1234]: info: ControllerMpd::getState Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces state update: player Nov 24 14:19:03 volumio volumio[1234]: info: ControllerMpd::getState Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:03 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand status took 3 milliseconds Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand clear took 3 milliseconds Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand status took 3 milliseconds Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand status took 1 milliseconds Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::parseState Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand load "http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3" Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::parseState Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::parseState Nov 24 14:19:03 volumio volumio[1234]: info: ControllerMpd::pushState Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:03 volumio volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 24 14:19:03 volumio volumio[1234]: info: ControllerMpd::pushState Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Nov 24 14:19:03 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:03 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:03 volumio volumio[1234]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 14ms Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 14ms Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:03 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:03 volumio volumio[1234]: info: Nov 24 14:19:03 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:03 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:03 volumio volumio[1234]: error: updateQueue error: null Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 14ms Nov 24 14:19:03 volumio volumio[1234]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 24 14:19:03 volumio volumio[1234]: verbose: ControllerMpd::parseTrackInfo Nov 24 14:19:03 volumio volumio[1234]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 24 14:19:03 volumio volumio[1234]: info: ------------------------------ 18ms Nov 24 14:19:04 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand add "http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3" Nov 24 14:19:04 volumio volumio[1234]: error: updateQueue error: null Nov 24 14:19:04 volumio volumio[1234]: error: updateQueue error: null Nov 24 14:19:04 volumio volumio[1234]: info: ------------------------------ 1189ms Nov 24 14:19:04 volumio volumio[1234]: info: ------------------------------ 1189ms Nov 24 14:19:04 volumio volumio[1234]: info: Nov 24 14:19:04 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:04 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:04 volumio volumio[1234]: info: sendMpdCommand add "http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3" took 1 milliseconds Nov 24 14:19:04 volumio volumio[1234]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 24 14:19:04 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand play Nov 24 14:19:04 volumio volumio[1234]: info: Nov 24 14:19:04 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:04 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:04 volumio volumio[1234]: info: Nov 24 14:19:04 volumio volumio[1234]: ---------------------------- MPD announces system playlist update Nov 24 14:19:04 volumio volumio[1234]: info: Ignoring MPD Status Update Nov 24 14:19:04 volumio volumio[1234]: info: ------------------------------ 2ms Nov 24 14:19:04 volumio volumio[1234]: info: sendMpdCommand play took 1 milliseconds Nov 24 14:19:04 volumio volumio[1234]: info: ------------------------------ 2ms Nov 24 14:19:04 volumio volumio[1234]: info: ------------------------------ 2ms Nov 24 14:19:05 volumio volumio[1234]: info: Nov 24 14:19:05 volumio volumio[1234]: ---------------------------- MPD announces state update: player Nov 24 14:19:05 volumio volumio[1234]: info: ControllerMpd::getState Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Nov 24 14:19:05 volumio volumio[1234]: info: Nov 24 14:19:05 volumio volumio[1234]: ---------------------------- MPD announces state update: player Nov 24 14:19:05 volumio volumio[1234]: info: ControllerMpd::getState Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Nov 24 14:19:05 volumio volumio[1234]: info: Nov 24 14:19:05 volumio volumio[1234]: ---------------------------- MPD announces state update: player Nov 24 14:19:05 volumio volumio[1234]: info: ControllerMpd::getState Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand status Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand status took 5 milliseconds Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::parseState Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand status took 12 milliseconds Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand status took 11 milliseconds Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand clearerror took 7 milliseconds Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::parseState Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::parseState Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::parseTrackInfo Nov 24 14:19:05 volumio volumio[1234]: info: ControllerMpd::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:05 volumio volumio[1234]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"shoutcast.mp3","artist":null,"album":null,"uri":"http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3","trackType":"mp3"} Nov 24 14:19:05 volumio volumio[1234]: verbose: CURRENT POSITION 0 Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::syncState stateService stop Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::syncState currentStatus stop Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: No code Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: ------------------------------ 43ms Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand clearerror took 31 milliseconds Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand clearerror took 31 milliseconds Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 24 14:19:05 volumio volumio[1234]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::parseTrackInfo Nov 24 14:19:05 volumio volumio[1234]: verbose: ControllerMpd::parseTrackInfo Nov 24 14:19:05 volumio volumio[1234]: info: ControllerMpd::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:05 volumio volumio[1234]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"shoutcast.mp3","artist":null,"album":null,"uri":"http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3","trackType":"mp3"} Nov 24 14:19:05 volumio volumio[1234]: verbose: CURRENT POSITION 0 Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::syncState stateService stop Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::syncState currentStatus stop Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: No code Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: ControllerMpd::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Nov 24 14:19:05 volumio volumio[1234]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"shoutcast.mp3","artist":null,"album":null,"uri":"http://api.spreaker.com/listen/user/6015567/episode/latest/shoutcast.mp3","trackType":"mp3"} Nov 24 14:19:05 volumio volumio[1234]: verbose: CURRENT POSITION 0 Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::syncState stateService stop Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::syncState currentStatus stop Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: No code Nov 24 14:19:05 volumio volumio[1234]: info: CoreStateMachine::pushState Nov 24 14:19:05 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Nov 24 14:19:05 volumio volumio[1234]: info: ------------------------------ 75ms Nov 24 14:19:05 volumio volumio[1234]: info: ------------------------------ 76ms Nov 24 14:19:33 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Nov 24 14:19:37 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 14:19:37 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 14:19:37 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Nov 24 14:19:37 volumio sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Nov 24 14:19:37 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:19:37 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 24 14:19:37 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Nov 24 14:19:38 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:19:38 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Nov 24 14:19:38 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 24 14:19:38 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:19:38 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Nov 24 14:19:38 volumio sudo[2251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 24 14:19:38 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:19:38 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root Nov 24 14:19:38 volumio sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 14:19:38 volumio sudo[2258]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:19:38 volumio sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 14:19:38 volumio sudo[2258]: pam_unix(sudo:session): session closed for user root Nov 24 14:19:38 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:19:38 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 24 14:19:38 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 24 14:19:38 volumio sudo[2260]: pam_unix(sudo:session): session closed for user root Nov 24 14:19:38 volumio sudo[2264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 24 14:19:38 volumio sudo[2264]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:19:45 volumio nmbd[874]: [2024/11/24 14:19:45.823341, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Nov 24 14:19:45 volumio nmbd[874]: my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.0.126. Nov 24 14:19:45 volumio nmbd[874]: [2024/11/24 14:19:45.823460, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Nov 24 14:19:45 volumio nmbd[874]: standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.0.126 Nov 24 14:19:45 volumio nmbd[874]: [2024/11/24 14:19:45.823502, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Nov 24 14:19:45 volumio nmbd[874]: my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.0.126. Nov 24 14:19:45 volumio nmbd[874]: [2024/11/24 14:19:45.823544, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Nov 24 14:19:45 volumio nmbd[874]: standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.0.126 Nov 24 14:19:45 volumio nmbd[874]: [2024/11/24 14:19:45.823583, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Nov 24 14:19:45 volumio nmbd[874]: my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.0.126. Nov 24 14:19:45 volumio nmbd[874]: [2024/11/24 14:19:45.823623, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Nov 24 14:19:45 volumio nmbd[874]: standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.0.126 Nov 24 14:19:47 volumio sudo[2264]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:01 volumio volumio[1234]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Nov 24 14:20:01 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Nov 24 14:20:02 volumio sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Nov 24 14:20:02 volumio sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:02 volumio sudo[2303]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:02 volumio volumio[1234]: info: Permissions for /etc/hostapd/hostapd.conf Nov 24 14:20:02 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 24 14:20:02 volumio volumio[1234]: info: Discovery: Restarting Advertising due to device name change Nov 24 14:20:02 volumio sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 24 14:20:02 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:02 volumio systemd[1]: Stopping Wireless Services... Nov 24 14:20:02 volumio kernel: wlan0: deauthenticating from 70:5d:cc:58:08:de by local choice (Reason: 3=DEAUTH_LEAVING) Nov 24 14:20:02 volumio dhcpcd[829]: wlan0: carrier lost Nov 24 14:20:02 volumio avahi-daemon[759]: Withdrawing address record for 192.168.0.126 on wlan0. Nov 24 14:20:02 volumio avahi-daemon[759]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.126. Nov 24 14:20:02 volumio avahi-daemon[759]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 24 14:20:02 volumio wpa_supplicant[1006]: wlan0: CTRL-EVENT-DISCONNECTED bssid=70:5d:cc:58:08:de reason=3 locally_generated=1 Nov 24 14:20:02 volumio volumio[1234]: info: Discovery: A device disappeared from network Nov 24 14:20:02 volumio volumio[1234]: info: Discovery: Device volumio disappeared from network Nov 24 14:20:02 volumio kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated Nov 24 14:20:02 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement) Nov 24 14:20:02 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Nov 24 14:20:02 volumio volumio[1234]: info: Discovery: Disconnected from remote: 192.168.0.123 Nov 24 14:20:02 volumio volumio[1234]: info: Discovery: A device disappeared from network Nov 24 14:20:02 volumio dhcpcd[829]: wlan0: deleting route to 192.168.0.0/24 Nov 24 14:20:02 volumio dhcpcd[829]: wlan0: deleting default route via 192.168.0.1 Nov 24 14:20:02 volumio wpa_supplicant[1006]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Nov 24 14:20:02 volumio wpa_supplicant[1006]: wlan0: CTRL-EVENT-TERMINATING Nov 24 14:20:02 volumio systemd[1]: wireless.service: Succeeded. Nov 24 14:20:02 volumio systemd[1]: Stopped Wireless Services. Nov 24 14:20:02 volumio systemd[1]: Starting Wireless Services... Nov 24 14:20:02 volumio wireless.js[2339]: Cleaning previous... Nov 24 14:20:03 volumio sudo[2353]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 24 14:20:03 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:03 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:03 volumio sudo[2355]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 24 14:20:03 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:03 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:03 volumio wireless.js[2339]: Stopped aP Nov 24 14:20:03 volumio sudo[2362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 24 14:20:03 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:03 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:03 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Nov 24 14:20:03 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Nov 24 14:20:03 volumio sudo[2364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 24 14:20:03 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:03 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:03 volumio sudo[2371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 24 14:20:03 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:03 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:03 volumio sudo[2373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 24 14:20:03 volumio sudo[2373]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:03 volumio volumio[1234]: info: Discovery: A device disappeared from network Nov 24 14:20:03 volumio volumio[1234]: info: Volumio Network Manager: Network status updated: 1 Nov 24 14:20:05 volumio ntpd[1223]: Deleting interface #4 wlan0, 192.168.0.126#123, interface stats: received=0, sent=0, dropped=0, active_time=147 secs Nov 24 14:20:06 volumio sudo[2373]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:06 volumio wireless.js[2339]: SETTING APPROPRIATE REG DOMAIN: BR Nov 24 14:20:06 volumio sudo[2395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 24 14:20:06 volumio sudo[2395]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:06 volumio sudo[2395]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:06 volumio sudo[2397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set BR Nov 24 14:20:06 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:06 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:06 volumio wireless.js[2339]: SUCCESSFULLY SET NEW REGDOMAIN: BR Nov 24 14:20:06 volumio wireless.js[2339]: Start wireless flow Nov 24 14:20:06 volumio wireless.js[2339]: Stopped hotspot (if there).. Nov 24 14:20:06 volumio sudo[2402]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 24 14:20:06 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:06 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:06 volumio sudo[2404]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 24 14:20:06 volumio sudo[2404]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:06 volumio sudo[2404]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:06 volumio wireless.js[2339]: DHCP IP Nov 24 14:20:06 volumio wpa_supplicant[2406]: Successfully initialized wpa_supplicant Nov 24 14:20:06 volumio wireless.js[2339]: Start ap Nov 24 14:20:06 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement) Nov 24 14:20:06 volumio kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement) Nov 24 14:20:06 volumio sudo[2407]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 24 14:20:06 volumio sudo[2407]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:06 volumio dhcpcd[2408]: sending commands to master dhcpcd process Nov 24 14:20:06 volumio dhcpcd[829]: control command: /sbin/dhcpcd Nov 24 14:20:06 volumio sudo[2407]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:07 volumio volumio[1234]: info: Discovery: Started advertising with name: Volumio Nov 24 14:20:07 volumio wireless.js[2339]: trying... Nov 24 14:20:07 volumio sudo[2412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:07 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:07 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:08 volumio volumio[1234]: info: Discovery: adding 67b5cca6-39bf-4208-82a2-f881a7aaf3ff Nov 24 14:20:08 volumio volumio[1234]: info: Discovery: Found device Volumio Nov 24 14:20:08 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Nov 24 14:20:08 volumio wireless.js[2339]: trying... Nov 24 14:20:08 volumio sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:08 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:08 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:09 volumio wireless.js[2339]: trying... Nov 24 14:20:09 volumio sudo[2419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:09 volumio sudo[2419]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:09 volumio sudo[2419]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:10 volumio wpa_supplicant[2409]: wlan0: SME: Trying to authenticate with 70:5d:cc:58:08:de (SSID='drone2_5G' freq=5745 MHz) Nov 24 14:20:10 volumio kernel: wlan0: authenticate with 70:5d:cc:58:08:de Nov 24 14:20:10 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Nov 24 14:20:10 volumio kernel: wlan0: send auth to 70:5d:cc:58:08:de (try 1/3) Nov 24 14:20:10 volumio wpa_supplicant[2409]: wlan0: Trying to associate with 70:5d:cc:58:08:de (SSID='drone2_5G' freq=5745 MHz) Nov 24 14:20:10 volumio kernel: wlan0: authenticated Nov 24 14:20:10 volumio kernel: wlan0: associate with 70:5d:cc:58:08:de (try 1/3) Nov 24 14:20:10 volumio wpa_supplicant[2409]: wlan0: Associated with 70:5d:cc:58:08:de Nov 24 14:20:10 volumio wpa_supplicant[2409]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 24 14:20:10 volumio wpa_supplicant[2409]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Nov 24 14:20:10 volumio kernel: wlan0: RX AssocResp from 70:5d:cc:58:08:de (capab=0x511 status=0 aid=4) Nov 24 14:20:10 volumio kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated Nov 24 14:20:10 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement) Nov 24 14:20:10 volumio kernel: wlan0: associated Nov 24 14:20:10 volumio wpa_supplicant[2409]: wlan0: WPA: Key negotiation completed with 70:5d:cc:58:08:de [PTK=CCMP GTK=CCMP] Nov 24 14:20:10 volumio wpa_supplicant[2409]: wlan0: CTRL-EVENT-CONNECTED - Connection to 70:5d:cc:58:08:de completed [id=0 id_str=] Nov 24 14:20:10 volumio dhcpcd[829]: wlan0: carrier acquired Nov 24 14:20:10 volumio dhcpcd[829]: wlan0: IAID 4b:11:ce:6c Nov 24 14:20:10 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 70:5d:cc:58:08:de Nov 24 14:20:10 volumio wireless.js[2339]: trying... Nov 24 14:20:10 volumio sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:10 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:10 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:11 volumio dhcpcd[829]: wlan0: soliciting an IPv6 router Nov 24 14:20:11 volumio dhcpcd[829]: wlan0: rebinding lease of 192.168.0.126 Nov 24 14:20:11 volumio dhcpcd[829]: wlan0: probing address 192.168.0.126/24 Nov 24 14:20:11 volumio wireless.js[2339]: trying... Nov 24 14:20:11 volumio sudo[2426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:11 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:11 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:12 volumio wireless.js[2339]: trying... Nov 24 14:20:12 volumio sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:12 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:12 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:13 volumio wireless.js[2339]: trying... Nov 24 14:20:13 volumio sudo[2437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:13 volumio sudo[2437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:13 volumio sudo[2437]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:14 volumio wireless.js[2339]: trying... Nov 24 14:20:14 volumio sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:14 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:14 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:15 volumio wireless.js[2339]: trying... Nov 24 14:20:15 volumio sudo[2456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:15 volumio sudo[2456]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:15 volumio sudo[2456]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:15 volumio dhcpcd[829]: wlan0: leased 192.168.0.126 for 7200 seconds Nov 24 14:20:15 volumio dhcpcd[829]: wlan0: adding route to 192.168.0.0/24 Nov 24 14:20:15 volumio dhcpcd[829]: wlan0: adding default route via 192.168.0.1 Nov 24 14:20:15 volumio avahi-daemon[759]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.126. Nov 24 14:20:15 volumio avahi-daemon[759]: New relevant interface wlan0.IPv4 for mDNS. Nov 24 14:20:15 volumio avahi-daemon[759]: Registering new address record for 192.168.0.126 on wlan0.IPv4. Nov 24 14:20:15 volumio kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement) Nov 24 14:20:15 volumio ntpd[1223]: ntpd exiting on signal 15 (Terminated) Nov 24 14:20:15 volumio ntpd[1223]: 39.118.108.191 local addr 192.168.0.119 -> Nov 24 14:20:15 volumio systemd[1]: Stopping Network Time Service... Nov 24 14:20:15 volumio ntpd[1223]: 146.56.40.151 local addr 192.168.0.119 -> Nov 24 14:20:15 volumio ntpd[1223]: 175.195.167.194 local addr 192.168.0.119 -> Nov 24 14:20:15 volumio ntpd[1223]: 152.70.237.122 local addr 192.168.0.119 -> Nov 24 14:20:15 volumio ntpd[1223]: 3.39.176.65 local addr 192.168.0.119 -> Nov 24 14:20:15 volumio ntpd[1223]: 141.164.43.237 local addr 192.168.0.119 -> Nov 24 14:20:15 volumio ntpd[1223]: 106.247.248.106 local addr 192.168.0.119 -> Nov 24 14:20:15 volumio systemd[1]: ntp.service: Succeeded. Nov 24 14:20:15 volumio systemd[1]: Stopped Network Time Service. Nov 24 14:20:16 volumio systemd[1]: Starting Network Time Service... Nov 24 14:20:16 volumio ntpd[2490]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 24 14:20:16 volumio ntpd[2490]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 24 14:20:16 volumio systemd[1]: Started Network Time Service. Nov 24 14:20:16 volumio ntpd[2496]: proto: precision = 1.440 usec (-19) Nov 24 14:20:16 volumio ntpd[2496]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 24 14:20:16 volumio ntpd[2496]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 24 14:20:16 volumio ntpd[2496]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 698 days ago Nov 24 14:20:16 volumio ntpd[2496]: Listen and drop on 0 v6wildcard [::]:123 Nov 24 14:20:16 volumio ntpd[2496]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 24 14:20:16 volumio ntpd[2496]: Listen normally on 2 lo 127.0.0.1:123 Nov 24 14:20:16 volumio ntpd[2496]: Listen normally on 3 eth0 192.168.0.119:123 Nov 24 14:20:16 volumio ntpd[2496]: Listen normally on 4 wlan0 192.168.0.126:123 Nov 24 14:20:16 volumio ntpd[2496]: Listening on routing socket on fd #21 for interface updates Nov 24 14:20:16 volumio ntpd[2496]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 24 14:20:16 volumio ntpd[2496]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 24 14:20:16 volumio volumio[1234]: info: Discovery: adding 9fef2d31-3703-4b93-bedd-c9fd569e7798 Nov 24 14:20:16 volumio volumio[1234]: info: Discovery: Found device Volumio Nov 24 14:20:16 volumio volumio[1234]: info: Discovery: Connecting to remote: 192.168.0.123 Nov 24 14:20:16 volumio volumio[1234]: info: Discovery: Connected to remote: 192.168.0.123 Nov 24 14:20:16 volumio volumio[1234]: info: Discovery: this is already registered, 67b5cca6-39bf-4208-82a2-f881a7aaf3ff Nov 24 14:20:16 volumio volumio[1234]: info: Discovery: Found device Volumio Nov 24 14:20:16 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Nov 24 14:20:16 volumio wireless.js[2339]: trying... Nov 24 14:20:16 volumio sudo[2501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 14:20:16 volumio sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:16 volumio sudo[2501]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:16 volumio wireless.js[2339]: Connected to: ----drone2_5G Nov 24 14:20:16 volumio wireless.js[2339]: ---- Nov 24 14:20:16 volumio sudo[2504]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 14:20:16 volumio sudo[2504]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:16 volumio sudo[2504]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:16 volumio wireless.js[2339]: ... joined AP, wlan0 IPv4 is 192.168.0.126, ipV6 is undefined Nov 24 14:20:16 volumio wireless.js[2339]: It's done! AP Nov 24 14:20:16 volumio iwconfig[2508]: Error for wireless request "Set Power Management" (8B2C) : Nov 24 14:20:16 volumio iwconfig[2508]: SET failed on device wlan0 ; Operation not supported. Nov 24 14:20:16 volumio systemd[1]: Started Wireless Services. Nov 24 14:20:16 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:17 volumio ntpd[2496]: Soliciting pool server 121.174.142.81 Nov 24 14:20:18 volumio ntpd[2496]: Soliciting pool server 3.39.176.65 Nov 24 14:20:18 volumio ntpd[2496]: Soliciting pool server 193.123.230.77 Nov 24 14:20:19 volumio ntpd[2496]: Soliciting pool server 175.193.3.234 Nov 24 14:20:19 volumio ntpd[2496]: Soliciting pool server 146.56.40.151 Nov 24 14:20:19 volumio ntpd[2496]: Soliciting pool server 194.0.5.123 Nov 24 14:20:20 volumio ntpd[2496]: Soliciting pool server 152.70.237.122 Nov 24 14:20:20 volumio ntpd[2496]: Soliciting pool server 39.118.108.191 Nov 24 14:20:20 volumio ntpd[2496]: Soliciting pool server 121.141.38.99 Nov 24 14:20:21 volumio ntpd[2496]: Soliciting pool server 141.164.43.237 Nov 24 14:20:21 volumio ntpd[2496]: Soliciting pool server 121.174.142.82 Nov 24 14:20:21 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Nov 24 14:20:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Nov 24 14:20:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Nov 24 14:20:21 volumio sudo[2556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 14:20:21 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:21 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:21 volumio sudo[2559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 24 14:20:21 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:21 volumio sudo[2559]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:22 volumio ntpd[2496]: Soliciting pool server 2401:c080:1c01:1e3:5400:5ff:fe2b:2e78 Nov 24 14:20:23 volumio volumio[1234]: info: Volumio Network Manager: Network status updated: 3 Nov 24 14:20:26 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Nov 24 14:20:26 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Nov 24 14:20:26 volumio volumio[1234]: info: Refreshing Cached IP Addresses Nov 24 14:20:26 volumio sudo[2581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Nov 24 14:20:26 volumio sudo[2581]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:26 volumio sudo[2581]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:26 volumio sudo[2584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 14:20:26 volumio sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:26 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:26 volumio sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 14:20:26 volumio sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:26 volumio systemd[1]: upmpdcli.service: Succeeded. Nov 24 14:20:26 volumio sudo[2587]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:27 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 14:20:27 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 14:20:28 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Nov 24 14:20:28 volumio sudo[2592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Nov 24 14:20:28 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:28 volumio sudo[2592]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:28 volumio sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 24 14:20:28 volumio sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:28 volumio sudo[2597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 24 14:20:28 volumio sudo[2604]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:28 volumio sudo[2597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:28 volumio sudo[2597]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:28 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 24 14:20:28 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 24 14:20:28 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 24 14:20:28 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:28 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:28 volumio sudo[2616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 14:20:28 volumio sudo[2620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 14:20:28 volumio sudo[2620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:28 volumio sudo[2620]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:28 volumio sudo[2616]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:28 volumio sudo[2616]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:28 volumio sudo[2624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 24 14:20:28 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:36 volumio sudo[2644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 14:20:36 volumio sudo[2644]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:36 volumio sudo[2644]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:37 volumio sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 14:20:37 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:37 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:37 volumio sudo[2650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 24 14:20:37 volumio sudo[2650]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 14:20:37 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 24 14:20:37 volumio sudo[2650]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:37 volumio volumio[1234]: info: Upmpdcli Daemon Started Nov 24 14:20:37 volumio volumio[2652]: writing RSA key Nov 24 14:20:37 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Nov 24 14:20:37 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root Nov 24 14:20:42 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 24 14:20:42 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:20:43 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 24 14:20:44 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:20:45 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 24 14:20:45 volumio volumio[1234]: info: Preload queue cleared Nov 24 14:20:46 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 24 14:20:46 volumio volumio[1234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 14:20:46 volumio volumio[1234]: TypeError: Cannot read property 'length' of undefined Nov 24 14:20:46 volumio volumio[1234]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 24 14:20:46 volumio volumio[1234]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 24 14:20:46 volumio volumio[1234]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 24 14:20:46 volumio volumio[1234]: at Parser.emit (events.js:400:28) Nov 24 14:20:46 volumio volumio[1234]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 24 14:20:46 volumio volumio[1234]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 24 14:20:46 volumio volumio[1234]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 24 14:20:46 volumio volumio[1234]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 24 14:20:46 volumio volumio[1234]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 24 14:20:46 volumio volumio[1234]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 24 14:20:46 volumio volumio[1234]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 24 14:20:46 volumio volumio[1234]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 24 14:20:46 volumio volumio[1234]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 24 14:20:46 volumio volumio[1234]: at IncomingMessage.emit (events.js:412:35) Nov 24 14:20:46 volumio volumio[1234]: at endReadableNT (internal/streams/readable.js:1333:12) Nov 24 14:20:46 volumio volumio[1234]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Nov 24 14:20:46 volumio volumio[1234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 14:20:46 volumio sudo[2713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 14:19 Nov 24 14:20:46 volumio sudo[2713]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"