-- Logs begin at Sun 2024-09-01 18:32:13 MSK, end at Sun 2024-09-01 18:35:01 MSK. -- Sep 01 18:34:02 volumio sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:34:02 volumio sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:02 volumio sudo[1964]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:02 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:34:02 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:02 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:03 volumio volumio[967]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.69 UA: Mozilla/5.0 (Linux; Android 12; Lenovo TB128FU Build/SKQ1.220119.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 01 18:34:03 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:34:03 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:03 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:03 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:34:03 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:03 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:03 volumio volumio[967]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.69 UA: Mozilla/5.0 (Linux; Android 12; Lenovo TB128FU Build/SKQ1.220119.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::volumioGetQueue Sep 01 18:34:04 volumio volumio[967]: info: CoreStateMachine::getQueue Sep 01 18:34:04 volumio volumio[967]: info: CorePlayQueue::getQueue Sep 01 18:34:04 volumio volumio[967]: info: Listing playlists Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 18:34:04 volumio volumio[967]: info: Received Get System Info Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:04 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 18:34:04 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 18:34:05 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 18:34:05 volumio volumio[967]: info: Received Get System Info Sep 01 18:34:05 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:05 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:05 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:05 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:05 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:05 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 01 18:34:06 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 18:34:06 volumio volumio[967]: info: Received Get System Info Sep 01 18:34:06 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:06 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:06 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:06 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:06 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:10 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 01 18:34:10 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 01 18:34:13 volumio volumio[967]: info: Getting webradio selection Sep 01 18:34:13 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:13 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 01 18:34:15 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::ClearQueue Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::stop Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::clearPlayQueue Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::saveQueue Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::addQueueItems Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::addQueueItems Sep 01 18:34:15 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:15 volumio volumio[967]: info: Adding Item to queue: http://stream.absolutradio.de/hq/mp3-160/radioplayer/ Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::saveQueue Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::updateTrackBlock Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::getTrackBlock Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPlay Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::play index 0 Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::stop Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::play index undefined Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::startPlaybackTimer Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:15 volumio volumio[967]: info: [1725204855446] ControllerWebradio::clearAddPlayTrack Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand stop took 7 milliseconds Sep 01 18:34:15 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:15 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand status took 6 milliseconds Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand clear took 6 milliseconds Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand load "http://stream.absolutradio.de/hq/mp3-160/radioplayer/" Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:15 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:15 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:15 volumio volumio[967]: error: updateQueue error: null Sep 01 18:34:15 volumio volumio[967]: info: ------------------------------ 17ms Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:15 volumio volumio[967]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 01 18:34:15 volumio volumio[967]: info: ------------------------------ 31ms Sep 01 18:34:15 volumio volumio[967]: error: Upnp client error: Error: This socket has been ended by the other party Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand add "http://stream.absolutradio.de/hq/mp3-160/radioplayer/" Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:15 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand status took 378 milliseconds Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand status took 375 milliseconds Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand add "http://stream.absolutradio.de/hq/mp3-160/radioplayer/" took 5 milliseconds Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 18:34:15 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand play Sep 01 18:34:15 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:15 volumio volumio[967]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 18:34:15 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:15 volumio volumio[967]: info: No code Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:15 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:15 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:15 volumio volumio[967]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 18:34:15 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:15 volumio volumio[967]: info: No code Sep 01 18:34:15 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:15 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:15 volumio volumio[967]: info: ------------------------------ 498ms Sep 01 18:34:15 volumio volumio[967]: info: ------------------------------ 498ms Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:15 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:15 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:15 volumio volumio[967]: info: ------------------------------ 135ms Sep 01 18:34:15 volumio volumio[967]: info: sendMpdCommand play took 128 milliseconds Sep 01 18:34:15 volumio volumio[967]: info: ------------------------------ 11ms Sep 01 18:34:15 volumio volumio[967]: info: ------------------------------ 9ms Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:15 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:15 volumio volumio[967]: info: Sep 01 18:34:15 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:15 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:16 volumio volumio[967]: info: ------------------------------ 17ms Sep 01 18:34:16 volumio volumio[967]: info: ------------------------------ 15ms Sep 01 18:34:16 volumio volumio[967]: info: Sep 01 18:34:16 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:16 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:16 volumio volumio[967]: info: Sep 01 18:34:16 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:16 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:16 volumio volumio[967]: info: sendMpdCommand status took 16 milliseconds Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:16 volumio volumio[967]: info: sendMpdCommand status took 14 milliseconds Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:16 volumio volumio[967]: info: Sep 01 18:34:16 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:16 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:16 volumio volumio[967]: info: sendMpdCommand clearerror took 23 milliseconds Sep 01 18:34:16 volumio volumio[967]: info: sendMpdCommand clearerror took 21 milliseconds Sep 01 18:34:16 volumio volumio[967]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 01 18:34:16 volumio volumio[967]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 18:34:16 volumio volumio[967]: info: sendMpdCommand status took 5 milliseconds Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:16 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:16 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:16 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:16 volumio volumio[967]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/hq/mp3-160/radioplayer/","trackType":""} Sep 01 18:34:16 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:16 volumio volumio[967]: info: No code Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:16 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:16 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:16 volumio volumio[967]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/hq/mp3-160/radioplayer/","trackType":""} Sep 01 18:34:16 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:16 volumio volumio[967]: info: No code Sep 01 18:34:16 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:16 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:17 volumio volumio[967]: info: ------------------------------ 179ms Sep 01 18:34:17 volumio volumio[967]: info: ------------------------------ 177ms Sep 01 18:34:17 volumio volumio[967]: info: sendMpdCommand playlistinfo took 155 milliseconds Sep 01 18:34:17 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:17 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:17 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:17 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:17 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:17 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:17 volumio volumio[967]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/hq/mp3-160/radioplayer/","trackType":""} Sep 01 18:34:17 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:17 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Sep 01 18:34:17 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:17 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:17 volumio volumio[967]: info: No code Sep 01 18:34:17 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:17 volumio volumio[967]: info: ------------------------------ 248ms Sep 01 18:34:19 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:19 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:19 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:19 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:19 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:21 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:21 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:21 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:21 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:21 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:22 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 18:34:22 volumio volumio[967]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 01 18:34:22 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 01 18:34:22 volumio volumio[967]: info: Received Get System Version Sep 01 18:34:22 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:34:22 volumio volumio[967]: info: Received Get System Info Sep 01 18:34:22 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:22 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:22 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:22 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:22 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:23 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:23 volumio volumio[967]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::ClearQueue Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::stop Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:23 volumio volumio[967]: info: CorePlayQueue::clearPlayQueue Sep 01 18:34:23 volumio volumio[967]: info: CorePlayQueue::saveQueue Sep 01 18:34:23 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::addQueueItems Sep 01 18:34:23 volumio volumio[967]: info: CorePlayQueue::addQueueItems Sep 01 18:34:23 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:23 volumio volumio[967]: info: Adding Item to queue: http://kdhx-ice.streamguys1.com:80/live Sep 01 18:34:23 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 01 18:34:23 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Sep 01 18:34:23 volumio volumio[967]: info: CorePlayQueue::saveQueue Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::updateTrackBlock Sep 01 18:34:23 volumio volumio[967]: info: CorePlayQueue::getTrackBlock Sep 01 18:34:23 volumio volumio[967]: info: CoreCommandRouter::volumioPlay Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::play index 0 Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::stop Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::play index undefined Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:23 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:23 volumio volumio[967]: info: CoreStateMachine::startPlaybackTimer Sep 01 18:34:23 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:23 volumio volumio[967]: info: [1725204863411] ControllerWebradio::clearAddPlayTrack Sep 01 18:34:23 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 18:34:23 volumio volumio[967]: info: sendMpdCommand stop took 4 milliseconds Sep 01 18:34:23 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 18:34:23 volumio volumio[967]: info: Sep 01 18:34:23 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:23 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:23 volumio volumio[967]: info: sendMpdCommand clear took 3 milliseconds Sep 01 18:34:23 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand load "http://kdhx-ice.streamguys1.com:80/live" Sep 01 18:34:23 volumio volumio[967]: info: Sep 01 18:34:23 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:23 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:23 volumio volumio[967]: info: Sep 01 18:34:23 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:23 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:23 volumio volumio[967]: error: updateQueue error: null Sep 01 18:34:23 volumio volumio[967]: info: ------------------------------ 10ms Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" Sep 01 18:34:24 volumio volumio[967]: error: updateQueue error: null Sep 01 18:34:24 volumio volumio[967]: error: updateQueue error: null Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:24 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 775ms Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 774ms Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" took 6 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand play Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:24 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:24 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 13ms Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand play took 9 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 7ms Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 6ms Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand status took 12 milliseconds Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand status took 31 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand clearerror took 22 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand status took 16 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand status took 14 milliseconds Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:24 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:24 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Sep 01 18:34:24 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 71ms Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:24 volumio volumio[967]: info: Sep 01 18:34:24 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand clearerror took 48 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand playlistinfo took 43 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand playlistinfo took 43 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand status took 13 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand status took 11 milliseconds Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:24 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:24 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Sep 01 18:34:24 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:24 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:24 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Sep 01 18:34:24 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:24 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:24 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Sep 01 18:34:24 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 180ms Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 165ms Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 164ms Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand playlistinfo took 110 milliseconds Sep 01 18:34:24 volumio volumio[967]: info: sendMpdCommand playlistinfo took 109 milliseconds Sep 01 18:34:24 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:24 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:24 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Sep 01 18:34:24 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:24 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:24 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:24 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Sep 01 18:34:24 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:24 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:24 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 179ms Sep 01 18:34:24 volumio volumio[967]: info: ------------------------------ 178ms Sep 01 18:34:30 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:30 volumio volumio[967]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::ClearQueue Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::stop Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:30 volumio volumio[967]: info: CorePlayQueue::clearPlayQueue Sep 01 18:34:30 volumio volumio[967]: info: CorePlayQueue::saveQueue Sep 01 18:34:30 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::addQueueItems Sep 01 18:34:30 volumio volumio[967]: info: CorePlayQueue::addQueueItems Sep 01 18:34:30 volumio volumio[967]: info: Preload queue cleared Sep 01 18:34:30 volumio volumio[967]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Sep 01 18:34:30 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 01 18:34:30 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Sep 01 18:34:30 volumio volumio[967]: info: CorePlayQueue::saveQueue Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::updateTrackBlock Sep 01 18:34:30 volumio volumio[967]: info: CorePlayQueue::getTrackBlock Sep 01 18:34:30 volumio volumio[967]: info: CoreCommandRouter::volumioPlay Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::play index 0 Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::stop Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::play index undefined Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:34:30 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:30 volumio volumio[967]: info: CoreStateMachine::startPlaybackTimer Sep 01 18:34:30 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:30 volumio volumio[967]: info: [1725204870231] ControllerWebradio::clearAddPlayTrack Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 18:34:30 volumio volumio[967]: info: Sep 01 18:34:30 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:30 volumio volumio[967]: info: sendMpdCommand stop took 5 milliseconds Sep 01 18:34:30 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 18:34:30 volumio volumio[967]: info: Sep 01 18:34:30 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:30 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:30 volumio volumio[967]: info: sendMpdCommand status took 4 milliseconds Sep 01 18:34:30 volumio volumio[967]: info: sendMpdCommand clear took 3 milliseconds Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac" Sep 01 18:34:30 volumio volumio[967]: info: Sep 01 18:34:30 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:30 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:30 volumio volumio[967]: info: Sep 01 18:34:30 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:30 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:30 volumio volumio[967]: error: updateQueue error: null Sep 01 18:34:30 volumio volumio[967]: info: ------------------------------ 11ms Sep 01 18:34:30 volumio volumio[967]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 01 18:34:30 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:30 volumio volumio[967]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 01 18:34:30 volumio volumio[967]: info: ------------------------------ 18ms Sep 01 18:34:32 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Sep 01 18:34:32 volumio volumio[967]: info: Sep 01 18:34:32 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:32 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:32 volumio volumio[967]: info: sendMpdCommand status took 1916 milliseconds Sep 01 18:34:32 volumio volumio[967]: info: sendMpdCommand status took 1913 milliseconds Sep 01 18:34:32 volumio volumio[967]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 4 milliseconds Sep 01 18:34:32 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:32 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 18:34:32 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand play Sep 01 18:34:32 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:32 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:32 volumio volumio[967]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 18:34:32 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:32 volumio volumio[967]: info: No code Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:32 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:32 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:32 volumio volumio[967]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 18:34:32 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:32 volumio volumio[967]: info: No code Sep 01 18:34:32 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:32 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:32 volumio volumio[967]: info: ------------------------------ 2061ms Sep 01 18:34:32 volumio volumio[967]: info: ------------------------------ 2061ms Sep 01 18:34:32 volumio volumio[967]: info: Sep 01 18:34:32 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:32 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:32 volumio volumio[967]: info: Sep 01 18:34:32 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:32 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:32 volumio volumio[967]: info: ------------------------------ 156ms Sep 01 18:34:32 volumio volumio[967]: info: sendMpdCommand play took 151 milliseconds Sep 01 18:34:32 volumio volumio[967]: info: ------------------------------ 6ms Sep 01 18:34:32 volumio volumio[967]: info: ------------------------------ 5ms Sep 01 18:34:32 volumio volumio[967]: info: Sep 01 18:34:32 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:32 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:32 volumio volumio[967]: info: Sep 01 18:34:32 volumio volumio[967]: ---------------------------- MPD announces system playlist update Sep 01 18:34:32 volumio volumio[967]: info: Ignoring MPD Status Update Sep 01 18:34:32 volumio volumio[967]: info: ------------------------------ 15ms Sep 01 18:34:32 volumio volumio[967]: info: ------------------------------ 13ms Sep 01 18:34:33 volumio volumio[967]: info: Sep 01 18:34:33 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:33 volumio volumio[967]: info: Sep 01 18:34:33 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:33 volumio volumio[967]: info: Sep 01 18:34:33 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:33 volumio volumio[967]: info: Sep 01 18:34:33 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand status took 22 milliseconds Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand status took 22 milliseconds Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand status took 23 milliseconds Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:33 volumio volumio[967]: info: Sep 01 18:34:33 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:33 volumio volumio[967]: info: Sep 01 18:34:33 volumio volumio[967]: ---------------------------- MPD announces state update: player Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::getState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand status took 74 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand clearerror took 63 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand clearerror took 61 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand clearerror took 58 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand playlistinfo took 30 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand playlistinfo took 29 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand status took 24 milliseconds Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:33 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:33 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Sep 01 18:34:33 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:33 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:33 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Sep 01 18:34:33 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:33 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:33 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Sep 01 18:34:33 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:33 volumio volumio[967]: info: ------------------------------ 205ms Sep 01 18:34:33 volumio volumio[967]: info: ------------------------------ 202ms Sep 01 18:34:33 volumio volumio[967]: info: ------------------------------ 201ms Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand status took 150 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand clearerror took 136 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand playlistinfo took 128 milliseconds Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand playlistinfo took 123 milliseconds Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseState Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:33 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:33 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Sep 01 18:34:33 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:33 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:33 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Sep 01 18:34:33 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:33 volumio volumio[967]: info: ------------------------------ 262ms Sep 01 18:34:33 volumio volumio[967]: info: ------------------------------ 208ms Sep 01 18:34:33 volumio volumio[967]: info: sendMpdCommand playlistinfo took 49 milliseconds Sep 01 18:34:33 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:34:33 volumio volumio[967]: info: ControllerMpd::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::servicePushState Sep 01 18:34:33 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Sep 01 18:34:33 volumio volumio[967]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Sep 01 18:34:33 volumio volumio[967]: verbose: CURRENT POSITION 0 Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState stateService pause Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:34:33 volumio volumio[967]: info: CoreStateMachine::pushState Sep 01 18:34:33 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Sep 01 18:34:33 volumio volumio[967]: info: ------------------------------ 239ms Sep 01 18:34:39 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:34:39 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:39 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:39 volumio sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:34:39 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:39 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:39 volumio volumio[967]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.69 UA: Mozilla/5.0 (Linux; Android 12; Lenovo TB128FU Build/SKQ1.220119.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 01 18:34:39 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 18:34:39 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 18:34:39 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:39 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:39 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:39 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:39 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:39 volumio sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:34:39 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:39 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:39 volumio sudo[2056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:34:39 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:34:39 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root Sep 01 18:34:39 volumio volumio[967]: verbose: New Socket.io Connection to 192.168.1.55 from 192.168.1.69 UA: Mozilla/5.0 (Linux; Android 12; Lenovo TB128FU Build/SKQ1.220119.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::volumioGetQueue Sep 01 18:34:40 volumio volumio[967]: info: CoreStateMachine::getQueue Sep 01 18:34:40 volumio volumio[967]: info: CorePlayQueue::getQueue Sep 01 18:34:40 volumio volumio[967]: info: Listing playlists Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 18:34:40 volumio volumio[967]: info: Received Get System Info Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:40 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 18:34:40 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 18:34:41 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 18:34:41 volumio volumio[967]: info: Received Get System Info Sep 01 18:34:41 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:41 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:41 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:41 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:41 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:41 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 18:34:41 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 01 18:34:46 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:49 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 18:34:51 volumio volumio[967]: info: Retrieving Cloud Streaming UI Sep 01 18:34:51 volumio volumio[967]: info: Getting Tidal Cloud Configuration Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 18:34:51 volumio volumio[967]: info: Getting Qobuz Cloud Configuration Sep 01 18:34:51 volumio volumio[967]: info: Asking plugin for UI Config Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 18:34:51 volumio volumio[967]: info: Getting Spotify Cloud Configuration Sep 01 18:34:51 volumio volumio[967]: info: Asking plugin for UI Config Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 18:34:51 volumio volumio[967]: info: Saving Spotify Acccount Sep 01 18:34:51 volumio volumio[967]: info: Got Tidal Cloud Configuration Sep 01 18:34:51 volumio volumio[967]: info: Got it Sep 01 18:34:51 volumio volumio[967]: info: Got it Sep 01 18:34:51 volumio volumio[967]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 18:34:51 volumio volumio[967]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:51 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 18:34:51 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 18:34:55 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 18:34:57 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 18:34:57 volumio volumio[967]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 01 18:34:57 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 01 18:34:57 volumio volumio[967]: info: Received Get System Version Sep 01 18:34:57 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:34:57 volumio volumio[967]: info: Received Get System Info Sep 01 18:34:57 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:57 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:57 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:57 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:57 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:34:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 18:34:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:34:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:34:59 volumio volumio[967]: info: Discovery: Getting this device information Sep 01 18:34:59 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Sep 01 18:34:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:35:01 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 01 18:35:01 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 01 18:35:01 volumio volumio[967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:35:01 volumio volumio[967]: Error: getaddrinfo -3007 Sep 01 18:35:01 volumio volumio[967]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Sep 01 18:35:01 volumio volumio[967]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Sep 01 18:35:01 volumio volumio[967]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Sep 01 18:35:01 volumio volumio[967]: code: -3007, Sep 01 18:35:01 volumio volumio[967]: errno: -3007, Sep 01 18:35:01 volumio volumio[967]: syscall: 'getaddrinfo' Sep 01 18:35:01 volumio volumio[967]: } Sep 01 18:35:01 volumio volumio[967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:35:01 volumio sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 18:34 Sep 01 18:35:01 volumio sudo[2070]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"