-- Logs begin at Thu 2019-02-14 13:12:01 MSK, end at Mon 2025-01-20 23:02:44 MSK. -- Jan 20 23:01:00 volumio3n volumio[1008]: info: CoreCommandRouter::volumioGetQueue Jan 20 23:01:00 volumio3n volumio[1008]: info: CoreStateMachine::getQueue Jan 20 23:01:00 volumio3n volumio[1008]: info: CorePlayQueue::getQueue Jan 20 23:01:01 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 20 23:01:01 volumio3n volumio[1008]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 20 23:01:01 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 20 23:01:01 volumio3n volumio[1008]: info: Received Get System Version Jan 20 23:01:01 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 23:01:01 volumio3n volumio[1008]: info: Received Get System Info Jan 20 23:01:01 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 23:01:01 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 23:01:01 volumio3n volumio[1008]: info: Discovery: Getting this device information Jan 20 23:01:01 volumio3n volumio[1008]: info: CoreCommandRouter::volumioGetState Jan 20 23:01:01 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 23:01:04 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 23:01:04 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 23:01:04 volumio3n volumio[1008]: info: Discovery: Getting this device information Jan 20 23:01:04 volumio3n volumio[1008]: info: CoreCommandRouter::volumioGetState Jan 20 23:01:04 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 23:01:17 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:01:22 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 20 23:01:22 volumio3n volumio[1008]: info: CURURI: playlists Jan 20 23:01:22 volumio3n volumio[1008]: info: Listing playlists Jan 20 23:01:22 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:01:34 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 20 23:01:34 volumio3n volumio[1008]: info: CURURI: playlists Jan 20 23:01:34 volumio3n volumio[1008]: info: Listing playlists Jan 20 23:01:34 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:01:35 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 23:01:35 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 23:01:35 volumio3n volumio[1008]: info: Discovery: Getting this device information Jan 20 23:01:35 volumio3n volumio[1008]: info: CoreCommandRouter::volumioGetState Jan 20 23:01:35 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 23:01:41 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 20 23:01:41 volumio3n volumio[1008]: info: CURURI: artists:// Jan 20 23:01:41 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:01:55 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 20 23:01:55 volumio3n volumio[1008]: info: CURURI: playlists Jan 20 23:01:55 volumio3n volumio[1008]: info: Listing playlists Jan 20 23:01:55 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:02 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 20 23:02:02 volumio3n volumio[1008]: info: CURURI: albums:// Jan 20 23:02:02 volumio3n volumio[1008]: info: listAlbums - loading Albums from cache Jan 20 23:02:02 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:04 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 23:02:04 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 23:02:04 volumio3n volumio[1008]: info: Discovery: Getting this device information Jan 20 23:02:04 volumio3n volumio[1008]: info: CoreCommandRouter::volumioGetState Jan 20 23:02:04 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 23:02:12 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 20 23:02:12 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:17 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 20 23:02:17 volumio3n volumio[1008]: info: CURURI: playlists Jan 20 23:02:17 volumio3n volumio[1008]: info: Listing playlists Jan 20 23:02:17 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:21 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 20 23:02:22 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:24 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 20 23:02:24 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:28 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::ClearQueue Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::stop Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::stPlaybackTimer Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::updateTrackBlock Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrackBlock Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::serviceStop Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::serviceStop Jan 20 23:02:28 volumio3n volumio[1008]: info: [1737403348346] ControllerWebradio::stop Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::clearPlayQueue Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::saveQueue Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushQueue Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::addQueueItems Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::addQueueItems Jan 20 23:02:28 volumio3n volumio[1008]: info: Preload queue cleared Jan 20 23:02:28 volumio3n volumio[1008]: info: Adding Item to queue: https://stream05.pcradio.ru/cassiopeia_station-med Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushQueue Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::saveQueue Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::updateTrackBlock Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrackBlock Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPlay Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::play index 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::stop Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::play index undefined Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::startPlaybackTimer Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: [1737403348396] ControllerWebradio::clearAddPlayTrack Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand stop Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand stop took 64 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand stop took 17 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand clear Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:28 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:28 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:28 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand status took 35 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand clear took 33 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand status took 18 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand status took 14 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand load "https://stream05.pcradio.ru/cassiopeia_station-med" Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:28 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 20 23:02:28 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:28 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:28 volumio3n volumio[1008]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 86ms Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 83ms Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:28 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:28 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:28 volumio3n volumio[1008]: error: updateQueue error: null Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 99ms Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 91 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:28 volumio3n volumio[1008]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 142ms Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand add "https://stream05.pcradio.ru/cassiopeia_station-med" Jan 20 23:02:28 volumio3n volumio[1008]: error: updateQueue error: null Jan 20 23:02:28 volumio3n volumio[1008]: error: updateQueue error: null Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:28 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 145ms Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 141ms Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand add "https://stream05.pcradio.ru/cassiopeia_station-med" took 8 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 20 23:02:28 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand play Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:28 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:28 volumio3n volumio[1008]: info: Jan 20 23:02:28 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:28 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 21ms Jan 20 23:02:28 volumio3n volumio[1008]: info: sendMpdCommand play took 17 milliseconds Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 14ms Jan 20 23:02:28 volumio3n volumio[1008]: info: ------------------------------ 11ms Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 18 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 15 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 11 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 48 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 46 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 43 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 35 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 14 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","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":"cassiopeia_station-med","artist":null,"album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus stop Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","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":"cassiopeia_station-med","artist":null,"album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","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":"cassiopeia_station-med","artist":null,"album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 163ms Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 221ms Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 219ms Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:30 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 165 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 159 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 158 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","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":"Игорь Илюшин - \"Slonokitopotam\"","artist":"Cassiopeia Station / Cassiopeia Station","album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","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":"Игорь Илюшин - \"Slonokitopotam\"","artist":"Cassiopeia Station / Cassiopeia Station","album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","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":"Игорь Илюшин - \"Slonokitopotam\"","artist":"Cassiopeia Station / Cassiopeia Station","album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 416ms Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 400ms Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 398ms Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:30 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces system playlist update Jan 20 23:02:30 volumio3n volumio[1008]: info: Ignoring MPD Status Update Jan 20 23:02:30 volumio3n volumio[1008]: info: Jan 20 23:02:30 volumio3n volumio[1008]: ---------------------------- MPD announces state update: player Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::getState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand status Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 278ms Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 270 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 68ms Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 64 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 63ms Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand status took 59 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseState Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 28 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: verbose: ControllerMpd::parseTrackInfo Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"52 Kbps","isStreaming":false,"title":"Игорь Илюшин - \"Slonokitopotam\"","artist":"Cassiopeia Station / Cassiopeia Station","album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1233,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"52 Kbps","isStreaming":false,"title":"Игорь Илюшин - \"Slonokitopotam\"","artist":"Cassiopeia Station / Cassiopeia Station","album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ControllerMpd::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::servicePushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CorePlayQueue::getTrack 0 Jan 20 23:02:30 volumio3n volumio[1008]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"52 Kbps","isStreaming":false,"title":"Игорь Илюшин - \"Slonokitopotam\"","artist":"Cassiopeia Station / Cassiopeia Station","album":null,"uri":"https://stream05.pcradio.ru/cassiopeia_station-med","trackType":""} Jan 20 23:02:30 volumio3n volumio[1008]: verbose: CURRENT POSITION 0 Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState stateService play Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::syncState currentStatus play Jan 20 23:02:30 volumio3n volumio[1008]: info: Received an update from plugin. extracting info from payload Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreStateMachine::pushState Jan 20 23:02:30 volumio3n volumio[1008]: info: CoreCommandRouter::volumioPushState Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 469ms Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 264ms Jan 20 23:02:30 volumio3n volumio[1008]: info: ------------------------------ 259ms Jan 20 23:02:34 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 23:02:34 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 23:02:34 volumio3n volumio[1008]: info: Discovery: Getting this device information Jan 20 23:02:34 volumio3n volumio[1008]: info: CoreCommandRouter::volumioGetState Jan 20 23:02:34 volumio3n volumio[1008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 23:02:41 volumio3n volumio[1008]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 23:02:41 volumio3n volumio[1008]: Error: Parse Error: Invalid header value char Jan 20 23:02:41 volumio3n volumio[1008]: at Socket.socketOnData (_http_client.js:515:22) Jan 20 23:02:41 volumio3n volumio[1008]: at Socket.emit (events.js:400:28) Jan 20 23:02:41 volumio3n volumio[1008]: at addChunk (internal/streams/readable.js:293:12) Jan 20 23:02:41 volumio3n volumio[1008]: at readableAddChunk (internal/streams/readable.js:267:9) Jan 20 23:02:41 volumio3n volumio[1008]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jan 20 23:02:41 volumio3n volumio[1008]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) { Jan 20 23:02:41 volumio3n volumio[1008]: bytesParsed: 85, Jan 20 23:02:41 volumio3n volumio[1008]: code: 'HPE_INVALID_HEADER_TOKEN', Jan 20 23:02:41 volumio3n volumio[1008]: reason: 'Invalid header value char', Jan 20 23:02:41 volumio3n volumio[1008]: rawPacket: Jan 20 23:02:41 volumio3n volumio[1008]: } Jan 20 23:02:41 volumio3n volumio[1008]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 23:02:44 volumio3n sudo[5461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 23:01 Jan 20 23:02:44 volumio3n sudo[5461]: 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="6e682b9410d28f3874f90b2d7789db824a859264" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7c81f20187867c3bd55475ead0f12800fe93082c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 08 Jun 2024 10:32:20 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="85fabbf1ff267d6584c35638894541bf"