-- Logs begin at Fri 2021-02-19 16:39:10 UTC, end at Wed 2024-10-16 10:21:21 UTC. -- Oct 16 10:20:06 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:20:13 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:20:21 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:20:29 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:20:36 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:20:40 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 10:20:40 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 10:20:40 volumio volumio[1207]: info: Discovery: Getting this device information Oct 16 10:20:40 volumio volumio[1207]: info: CoreCommandRouter::volumioGetState Oct 16 10:20:40 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 16 10:20:40 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 10:20:40 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 10:20:41 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 10:20:41 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 10:20:41 volumio volumio[1207]: info: Discovery: Getting this device information Oct 16 10:20:41 volumio volumio[1207]: info: CoreCommandRouter::volumioGetState Oct 16 10:20:41 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 16 10:20:41 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 10:20:41 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 10:20:44 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:20:45 volumio sudo[7196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 10:20:45 volumio sudo[7196]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 10:20:45 volumio sudo[7198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 10:20:45 volumio sudo[7196]: pam_unix(sudo:session): session closed for user root Oct 16 10:20:45 volumio sudo[7198]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 10:20:45 volumio sudo[7198]: pam_unix(sudo:session): session closed for user root Oct 16 10:20:45 volumio sudo[7214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 10:20:45 volumio sudo[7214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 10:20:45 volumio sudo[7216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 10:20:45 volumio sudo[7214]: pam_unix(sudo:session): session closed for user root Oct 16 10:20:45 volumio sudo[7216]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 10:20:45 volumio sudo[7216]: pam_unix(sudo:session): session closed for user root Oct 16 10:20:45 volumio volumio[1207]: info: CoreCommandRouter::volumioGetState Oct 16 10:20:45 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 10:20:45 volumio volumio[1207]: info: Listing playlists Oct 16 10:20:45 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 10:20:45 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 10:20:45 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 10:20:45 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 10:20:51 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:20:57 volumio volumio[1207]: info: CoreCommandRouter::volumioGetQueue Oct 16 10:20:57 volumio volumio[1207]: info: CoreStateMachine::getQueue Oct 16 10:20:57 volumio volumio[1207]: info: CorePlayQueue::getQueue Oct 16 10:20:58 volumio volumio[1207]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 10:20:58 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 10:20:59 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:21:00 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 16 10:21:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 10:21:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 10:21:01 volumio volumio[1207]: info: Discovery: Getting this device information Oct 16 10:21:01 volumio volumio[1207]: info: CoreCommandRouter::volumioGetState Oct 16 10:21:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 16 10:21:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 10:21:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 10:21:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::ClearQueue Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::stop Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:03 volumio volumio[1207]: UNSET VOLATILE Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::updateTrackBlock Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::getTrackBlock Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::serviceStop Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::serviceStop Oct 16 10:21:03 volumio volumio[1207]: info: ControllerMpd::stop Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::clearPlayQueue Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::saveQueue Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushQueue Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::addQueueItems Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::addQueueItems Oct 16 10:21:03 volumio volumio[1207]: info: Adding Item to queue: weblinn/2 Oct 16 10:21:03 volumio volumio[1207]: info: Exploding uri weblinn/2 in service personal_radio Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::saveQueue Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushQueue Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::updateTrackBlock Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::getTrackBlock Oct 16 10:21:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPlay Oct 16 10:21:03 volumio volumio[1207]: UNSET VOLATILE Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::play index 0 Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::stop Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:03 volumio volumio[1207]: UNSET VOLATILE Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::play index undefined Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::startPlaybackTimer Oct 16 10:21:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand stop took 26 milliseconds Oct 16 10:21:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:03 volumio volumio[1207]: info: Oct 16 10:21:03 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 16 10:21:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand stop took 14 milliseconds Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:03 volumio volumio[1207]: info: Oct 16 10:21:03 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 16 10:21:03 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand status took 4 milliseconds Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand clear took 4 milliseconds Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8004/stream" Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:03 volumio volumio[1207]: error: updateQueue error: null Oct 16 10:21:03 volumio volumio[1207]: info: Oct 16 10:21:03 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 16 10:21:03 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 16 10:21:03 volumio volumio[1207]: info: ------------------------------ 4ms Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand add "http://radio.linnrecords.com:8004/stream" took 2 milliseconds Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand play Oct 16 10:21:03 volumio volumio[1207]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 10:21:03 volumio volumio[1207]: info: ------------------------------ 19ms Oct 16 10:21:03 volumio volumio[1207]: info: ------------------------------ 12ms Oct 16 10:21:03 volumio volumio[1207]: info: sendMpdCommand play took 9 milliseconds Oct 16 10:21:03 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 10:21:04 volumio volumio[1207]: info: Oct 16 10:21:04 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 16 10:21:04 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:04 volumio volumio[1207]: info: Oct 16 10:21:04 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 16 10:21:04 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 16 10:21:04 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 16 10:21:04 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:04 volumio volumio[1207]: info: ------------------------------ 2ms Oct 16 10:21:04 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 10:21:04 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:04 volumio volumio[1207]: info: ControllerMpd::pushState Oct 16 10:21:04 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 16 10:21:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:04 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Gottlieb Wallisch - Piano Concerto No. 13 in C Maj [1bh]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Oct 16 10:21:04 volumio volumio[1207]: verbose: CURRENT POSITION 0 Oct 16 10:21:04 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 16 10:21:04 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 16 10:21:04 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:04 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:04 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:04 volumio volumio[1207]: info: ------------------------------ 9ms Oct 16 10:21:06 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::ClearQueue Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::stop Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:12 volumio volumio[1207]: UNSET VOLATILE Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::updateTrackBlock Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::getTrackBlock Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::serviceStop Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::serviceStop Oct 16 10:21:12 volumio volumio[1207]: info: ControllerMpd::stop Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::clearPlayQueue Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::saveQueue Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::volumioPushQueue Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::addQueueItems Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::addQueueItems Oct 16 10:21:12 volumio volumio[1207]: info: Adding Item to queue: weblinn/1 Oct 16 10:21:12 volumio volumio[1207]: info: Exploding uri weblinn/1 in service personal_radio Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::saveQueue Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::volumioPushQueue Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::updateTrackBlock Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::getTrackBlock Oct 16 10:21:12 volumio volumio[1207]: info: CoreCommandRouter::volumioPlay Oct 16 10:21:12 volumio volumio[1207]: UNSET VOLATILE Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::play index 0 Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::stop Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:12 volumio volumio[1207]: UNSET VOLATILE Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::play index undefined Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::startPlaybackTimer Oct 16 10:21:12 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 10:21:12 volumio volumio[1207]: info: Oct 16 10:21:12 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand stop took 43 milliseconds Oct 16 10:21:12 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:12 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand stop took 34 milliseconds Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:12 volumio volumio[1207]: info: Oct 16 10:21:12 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 16 10:21:12 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand clear took 2 milliseconds Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8003/stream" Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:12 volumio volumio[1207]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 16 10:21:12 volumio volumio[1207]: info: ------------------------------ 5ms Oct 16 10:21:12 volumio volumio[1207]: error: updateQueue error: null Oct 16 10:21:12 volumio volumio[1207]: info: Oct 16 10:21:12 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 16 10:21:12 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 16 10:21:12 volumio volumio[1207]: info: ------------------------------ 3ms Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand add "http://radio.linnrecords.com:8003/stream" took 3 milliseconds Oct 16 10:21:12 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand play Oct 16 10:21:12 volumio volumio[1207]: info: ------------------------------ 3ms Oct 16 10:21:12 volumio volumio[1207]: info: sendMpdCommand play took 1 milliseconds Oct 16 10:21:12 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 10:21:13 volumio volumio[1207]: info: Oct 16 10:21:13 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 16 10:21:13 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:13 volumio volumio[1207]: info: Oct 16 10:21:13 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 16 10:21:13 volumio volumio[1207]: info: Oct 16 10:21:13 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 16 10:21:13 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 16 10:21:13 volumio volumio[1207]: info: Oct 16 10:21:13 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 16 10:21:13 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:13 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 16 10:21:13 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:13 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 16 10:21:13 volumio volumio[1207]: info: ------------------------------ 4ms Oct 16 10:21:13 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 16 10:21:13 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:13 volumio volumio[1207]: info: ControllerMpd::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 16 10:21:13 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:13 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Various Artists - 7 Fantasien, Op. 116 No. 2: In","artist":"Linn Radio","album":null,"uri":"http://radio.linnrecords.com:8003/stream","trackType":""} Oct 16 10:21:13 volumio volumio[1207]: verbose: CURRENT POSITION 0 Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:13 volumio volumio[1207]: info: ------------------------------ 11ms Oct 16 10:21:13 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 10:21:13 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:13 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:13 volumio volumio[1207]: info: ControllerMpd::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 16 10:21:13 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:13 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Various Artists - 7 Fantasien, Op. 116 No. 2: In","artist":"Linn Radio","album":null,"uri":"http://radio.linnrecords.com:8003/stream","trackType":""} Oct 16 10:21:13 volumio volumio[1207]: verbose: CURRENT POSITION 0 Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 16 10:21:13 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:13 volumio volumio[1207]: info: ControllerMpd::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 16 10:21:13 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:13 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Various Artists - 7 Fantasien, Op. 116 No. 2: In","artist":"Linn Radio","album":null,"uri":"http://radio.linnrecords.com:8003/stream","trackType":""} Oct 16 10:21:13 volumio volumio[1207]: verbose: CURRENT POSITION 0 Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 16 10:21:13 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:13 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:13 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:13 volumio volumio[1207]: info: ------------------------------ 28ms Oct 16 10:21:13 volumio volumio[1207]: info: ------------------------------ 27ms Oct 16 10:21:14 volumio wpa_supplicant[896]: wlan0: Failed to initiate sched scan Oct 16 10:21:19 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::ClearQueue Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::stop Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 10:21:20 volumio volumio[1207]: UNSET VOLATILE Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::updateTrackBlock Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrackBlock Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::serviceStop Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::serviceStop Oct 16 10:21:20 volumio volumio[1207]: info: ControllerMpd::stop Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::clearPlayQueue Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::saveQueue Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushQueue Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::addQueueItems Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::addQueueItems Oct 16 10:21:20 volumio volumio[1207]: info: Adding Item to queue: webkbs/0 Oct 16 10:21:20 volumio volumio[1207]: info: Exploding uri webkbs/0 in service personal_radio Oct 16 10:21:20 volumio volumio[1207]: info: sendMpdCommand stop took 34 milliseconds Oct 16 10:21:20 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:20 volumio volumio[1207]: info: Oct 16 10:21:20 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 16 10:21:20 volumio volumio[1207]: info: ControllerMpd::getState Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 16 10:21:20 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:20 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 16 10:21:20 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: 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":"Various Artists - 7 Fantasien, Op. 116 No. 2: In","artist":"Linn Radio","album":null,"uri":"http://radio.linnrecords.com:8003/stream","trackType":""} Oct 16 10:21:20 volumio volumio[1207]: verbose: CURRENT POSITION 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: No code Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 16 10:21:20 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 16 10:21:20 volumio volumio[1207]: info: ControllerMpd::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: 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":"Various Artists - 7 Fantasien, Op. 116 No. 2: In","artist":"Linn Radio","album":null,"uri":"http://radio.linnrecords.com:8003/stream","trackType":""} Oct 16 10:21:20 volumio volumio[1207]: verbose: CURRENT POSITION 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: No code Oct 16 10:21:20 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 16 10:21:20 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 16 10:21:20 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 10:21:20 volumio volumio[1207]: info: ------------------------------ 30ms Oct 16 10:21:20 volumio volumio[1207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 10:21:20 volumio volumio[1207]: ReferenceError: self is not defined Oct 16 10:21:20 volumio volumio[1207]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Oct 16 10:21:20 volumio volumio[1207]: at /data/plugins/music_service/personal_radio/index.js:487:12 Oct 16 10:21:20 volumio volumio[1207]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Oct 16 10:21:20 volumio volumio[1207]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Oct 16 10:21:20 volumio volumio[1207]: at emitOne (events.js:116:13) Oct 16 10:21:20 volumio volumio[1207]: at Request.emit (events.js:211:7) Oct 16 10:21:20 volumio volumio[1207]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Oct 16 10:21:20 volumio volumio[1207]: at emitOne (events.js:116:13) Oct 16 10:21:20 volumio volumio[1207]: at ClientRequest.emit (events.js:211:7) Oct 16 10:21:20 volumio volumio[1207]: at Socket.socketErrorListener (_http_client.js:387:9) Oct 16 10:21:20 volumio volumio[1207]: at emitOne (events.js:116:13) Oct 16 10:21:20 volumio volumio[1207]: at Socket.emit (events.js:211:7) Oct 16 10:21:20 volumio volumio[1207]: at emitErrorNT (internal/streams/destroy.js:64:8) Oct 16 10:21:20 volumio volumio[1207]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Oct 16 10:21:20 volumio volumio[1207]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 16 10:21:20 volumio volumio[1207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 10:21:21 volumio sudo[7317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-16 10:20 Oct 16 10:21:21 volumio sudo[7317]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e81b2037a65d8f554cb0c857a1007d659ad7f838" VOLUMIO_FE_VERSION="e489037accd8e0b2dcbd6e0b054400d8cd36d4e2" VOLUMIO_BE_VERSION="25875b197c75cd1ac7eb5664d94cdd19afb06985" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Feb 19 17:39:10 CET 2021" VOLUMIO_VERSION="2.873" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="781d4c9fbf86d562939968306a6a26f4"