-- Logs begin at Thu 2012-07-26 07:36:12 UTC, end at Fri 2024-03-29 10:36:01 UTC. -- Mar 29 10:35:01 volumio volumio[972]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 10:35:01 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 10:35:01 volumio volumio[972]: info: CoreCommandRouter::volumioGetQueue Mar 29 10:35:01 volumio volumio[972]: info: CoreStateMachine::getQueue Mar 29 10:35:01 volumio volumio[972]: info: CorePlayQueue::getQueue Mar 29 10:35:01 volumio volumio[972]: info: Listing playlists Mar 29 10:35:06 volumio volumio[972]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 29 10:35:20 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 29 10:35:20 volumio volumio[972]: info: ControllerPersonalRadio::handleBrowseUri Mar 29 10:35:24 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 29 10:35:24 volumio volumio[972]: info: ControllerPersonalRadio::handleBrowseUri Mar 29 10:35:25 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 10:35:25 volumio volumio[972]: info: CoreStateMachine::ClearQueue Mar 29 10:35:25 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:25 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:25 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:25 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Mar 29 10:35:25 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:25 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:25 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:26 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:26 volumio volumio[972]: info: CoreStateMachine::addQueueItems Mar 29 10:35:26 volumio volumio[972]: info: CorePlayQueue::addQueueItems Mar 29 10:35:26 volumio volumio[972]: info: Adding Item to queue: webkbs/1 Mar 29 10:35:26 volumio volumio[972]: info: Exploding uri webkbs/1 in service personal_radio Mar 29 10:35:29 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:29 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:29 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:29 volumio volumio[972]: info: CoreCommandRouter::volumioPlay Mar 29 10:35:29 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::play index 0 Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:29 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::play index undefined Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:29 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:29 volumio volumio[972]: info: CoreStateMachine::startPlaybackTimer Mar 29 10:35:29 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:29 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:30 volumio volumio[972]: info: sendMpdCommand stop took 1171 milliseconds Mar 29 10:35:30 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 10:35:30 volumio volumio[972]: info: Mar 29 10:35:30 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:30 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:30 volumio volumio[972]: info: sendMpdCommand clear took 17 milliseconds Mar 29 10:35:30 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand add "null" Mar 29 10:35:30 volumio volumio[972]: error: updateQueue error: null Mar 29 10:35:30 volumio volumio[972]: info: ------------------------------ 27ms Mar 29 10:35:30 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 10:35:30 volumio volumio[972]: info: CoreStateMachine::ClearQueue Mar 29 10:35:30 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:30 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:30 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:30 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Mar 29 10:35:30 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:30 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:30 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:30 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:30 volumio volumio[972]: info: CoreStateMachine::addQueueItems Mar 29 10:35:30 volumio volumio[972]: info: CorePlayQueue::addQueueItems Mar 29 10:35:30 volumio volumio[972]: info: Adding Item to queue: webkbs/1 Mar 29 10:35:30 volumio volumio[972]: info: Exploding uri webkbs/1 in service personal_radio Mar 29 10:35:32 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:32 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:32 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:32 volumio volumio[972]: info: CoreCommandRouter::volumioPlay Mar 29 10:35:32 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::play index 0 Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:32 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::play index undefined Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:32 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:32 volumio volumio[972]: info: CoreStateMachine::startPlaybackTimer Mar 29 10:35:32 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:32 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:32 volumio volumio[972]: info: sendMpdCommand stop took 29 milliseconds Mar 29 10:35:32 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 10:35:32 volumio volumio[972]: info: Mar 29 10:35:32 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:32 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:32 volumio volumio[972]: info: sendMpdCommand clear took 5 milliseconds Mar 29 10:35:32 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand add "null" Mar 29 10:35:32 volumio volumio[972]: error: updateQueue error: null Mar 29 10:35:32 volumio volumio[972]: info: ------------------------------ 6ms Mar 29 10:35:36 volumio volumio[972]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 29 10:35:39 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 29 10:35:40 volumio volumio[972]: info: ControllerPersonalRadio::handleBrowseUri Mar 29 10:35:41 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::ClearQueue Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:41 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:41 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Mar 29 10:35:41 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:41 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:41 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:41 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::addQueueItems Mar 29 10:35:41 volumio volumio[972]: info: CorePlayQueue::addQueueItems Mar 29 10:35:41 volumio volumio[972]: info: Adding Item to queue: weblinn/0 Mar 29 10:35:41 volumio volumio[972]: info: Exploding uri weblinn/0 in service personal_radio Mar 29 10:35:41 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:41 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:41 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:41 volumio volumio[972]: info: CoreCommandRouter::volumioPlay Mar 29 10:35:41 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::play index 0 Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:41 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::play index undefined Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:41 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::startPlaybackTimer Mar 29 10:35:41 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:41 volumio volumio[972]: info: sendMpdCommand stop took 44 milliseconds Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 10:35:41 volumio volumio[972]: info: Mar 29 10:35:41 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:41 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:41 volumio volumio[972]: info: sendMpdCommand clear took 8 milliseconds Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8000/stream" Mar 29 10:35:41 volumio volumio[972]: error: updateQueue error: null Mar 29 10:35:41 volumio volumio[972]: info: Mar 29 10:35:41 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:41 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:41 volumio volumio[972]: info: ------------------------------ 11ms Mar 29 10:35:41 volumio volumio[972]: info: sendMpdCommand add "http://radio.linnrecords.com:8000/stream" took 5 milliseconds Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand play Mar 29 10:35:41 volumio volumio[972]: info: ------------------------------ 15ms Mar 29 10:35:41 volumio volumio[972]: info: sendMpdCommand play took 8 milliseconds Mar 29 10:35:41 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 10:35:41 volumio volumio[972]: info: Mar 29 10:35:41 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:41 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:41 volumio volumio[972]: info: sendMpdCommand status took 18 milliseconds Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:41 volumio volumio[972]: info: Mar 29 10:35:41 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:41 volumio volumio[972]: info: Mar 29 10:35:41 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:41 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:41 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:41 volumio volumio[972]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 29 10:35:41 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:41 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:42 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:42 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tommy Smith - Gnossienne No.1 [11]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Mar 29 10:35:42 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:42 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:42 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus stop Mar 29 10:35:42 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:42 volumio volumio[972]: info: ------------------------------ 73ms Mar 29 10:35:42 volumio volumio[972]: info: sendMpdCommand status took 39 milliseconds Mar 29 10:35:42 volumio volumio[972]: info: ------------------------------ 50ms Mar 29 10:35:42 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:42 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:42 volumio volumio[972]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 29 10:35:42 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:42 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:42 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:42 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tommy Smith - Gnossienne No.1 [11]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Mar 29 10:35:42 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:42 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:42 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus play Mar 29 10:35:42 volumio volumio[972]: info: Received an update from plugin. extracting info from payload Mar 29 10:35:42 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:42 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:42 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:42 volumio volumio[972]: info: ------------------------------ 121ms Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::ClearQueue Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:44 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::stPlaybackTimer Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::serviceStop Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::serviceStop Mar 29 10:35:44 volumio volumio[972]: info: ControllerMpd::stop Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::addQueueItems Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::addQueueItems Mar 29 10:35:44 volumio volumio[972]: info: Adding Item to queue: weblinn/1 Mar 29 10:35:44 volumio volumio[972]: info: Exploding uri weblinn/1 in service personal_radio Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPlay Mar 29 10:35:44 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::play index 0 Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:44 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::play index undefined Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::startPlaybackTimer Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:44 volumio volumio[972]: info: Mar 29 10:35:44 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand stop took 93 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand stop took 34 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 10:35:44 volumio volumio[972]: info: Mar 29 10:35:44 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:44 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand status took 10 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand status took 9 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand clear took 7 milliseconds Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8003/stream" Mar 29 10:35:44 volumio volumio[972]: error: updateQueue error: null Mar 29 10:35:44 volumio volumio[972]: info: Mar 29 10:35:44 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:44 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:44 volumio volumio[972]: info: ------------------------------ 14ms Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand add "http://radio.linnrecords.com:8003/stream" took 10 milliseconds Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand play Mar 29 10:35:44 volumio volumio[972]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 10:35:44 volumio volumio[972]: info: ------------------------------ 38ms Mar 29 10:35:44 volumio volumio[972]: info: ------------------------------ 17ms Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand play took 7 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 10:35:44 volumio volumio[972]: info: Mar 29 10:35:44 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:44 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:44 volumio volumio[972]: info: Mar 29 10:35:44 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:44 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:44 volumio volumio[972]: info: Mar 29 10:35:44 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:44 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand status took 21 milliseconds Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:44 volumio volumio[972]: info: ------------------------------ 27ms Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand status took 20 milliseconds Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:44 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:44 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Barb Jungr - One Morning In May / The Pushe","artist":"Linn Radio","album":null,"uri":"http://radio.linnrecords.com:8003/stream","trackType":""} Mar 29 10:35:44 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus stop Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:44 volumio volumio[972]: info: ------------------------------ 84ms Mar 29 10:35:44 volumio volumio[972]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 29 10:35:44 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:44 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:44 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:44 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Barb Jungr - One Morning In May / The Pushe","artist":"Linn Radio","album":null,"uri":"http://radio.linnrecords.com:8003/stream","trackType":""} Mar 29 10:35:44 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus play Mar 29 10:35:44 volumio volumio[972]: info: Received an update from plugin. extracting info from payload Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:44 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:44 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:44 volumio volumio[972]: info: ------------------------------ 113ms Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::ClearQueue Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:47 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::stPlaybackTimer Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::serviceStop Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::serviceStop Mar 29 10:35:47 volumio volumio[972]: info: ControllerMpd::stop Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::addQueueItems Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::addQueueItems Mar 29 10:35:47 volumio volumio[972]: info: Adding Item to queue: weblinn/2 Mar 29 10:35:47 volumio volumio[972]: info: Exploding uri weblinn/2 in service personal_radio Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:47 volumio volumio[972]: info: CoreCommandRouter::volumioPlay Mar 29 10:35:47 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::play index 0 Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:47 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::play index undefined Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:47 volumio volumio[972]: info: CoreStateMachine::startPlaybackTimer Mar 29 10:35:47 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:47 volumio volumio[972]: info: Mar 29 10:35:47 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:47 volumio volumio[972]: info: sendMpdCommand stop took 116 milliseconds Mar 29 10:35:47 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:47 volumio volumio[972]: info: sendMpdCommand stop took 43 milliseconds Mar 29 10:35:47 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 10:35:47 volumio volumio[972]: info: Mar 29 10:35:47 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:47 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:47 volumio volumio[972]: info: sendMpdCommand status took 20 milliseconds Mar 29 10:35:47 volumio volumio[972]: info: sendMpdCommand status took 19 milliseconds Mar 29 10:35:47 volumio volumio[972]: info: sendMpdCommand clear took 10 milliseconds Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:47 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8004/stream" Mar 29 10:35:48 volumio volumio[972]: error: updateQueue error: null Mar 29 10:35:48 volumio volumio[972]: info: Mar 29 10:35:48 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:48 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 25ms Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand add "http://radio.linnrecords.com:8004/stream" took 12 milliseconds Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand play Mar 29 10:35:48 volumio volumio[972]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 59ms Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 13ms Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand play took 4 milliseconds Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 10:35:48 volumio volumio[972]: info: Mar 29 10:35:48 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:48 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:48 volumio volumio[972]: info: Mar 29 10:35:48 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:48 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:48 volumio volumio[972]: info: Mar 29 10:35:48 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand status took 13 milliseconds Mar 29 10:35:48 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:48 volumio volumio[972]: info: Mar 29 10:35:48 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:48 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:48 volumio volumio[972]: info: Mar 29 10:35:48 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:48 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 19ms Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand status took 15 milliseconds Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:48 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:48 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:48 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pavlo Beznosiuk - Partita No. II in D minor - Al [1hP]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Mar 29 10:35:48 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus stop Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 78ms Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 61ms Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand status took 61 milliseconds Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:48 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:48 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:48 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pavlo Beznosiuk - Partita No. II in D minor - Al [1hP]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Mar 29 10:35:48 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus play Mar 29 10:35:48 volumio volumio[972]: info: Received an update from plugin. extracting info from payload Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 167ms Mar 29 10:35:48 volumio volumio[972]: info: sendMpdCommand playlistinfo took 111 milliseconds Mar 29 10:35:48 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:48 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:48 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:48 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pavlo Beznosiuk - Partita No. II in D minor - Al [1hP]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""} Mar 29 10:35:48 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus play Mar 29 10:35:48 volumio volumio[972]: info: Received an update from plugin. extracting info from payload Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:48 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:48 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:48 volumio volumio[972]: info: ------------------------------ 230ms Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::ClearQueue Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:52 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::stPlaybackTimer Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::serviceStop Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::serviceStop Mar 29 10:35:52 volumio volumio[972]: info: ControllerMpd::stop Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::addQueueItems Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::addQueueItems Mar 29 10:35:52 volumio volumio[972]: info: Adding Item to queue: weblinn/0 Mar 29 10:35:52 volumio volumio[972]: info: Exploding uri weblinn/0 in service personal_radio Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::volumioPlay Mar 29 10:35:52 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::play index 0 Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:52 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::play index undefined Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::startPlaybackTimer Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:52 volumio volumio[972]: info: Mar 29 10:35:52 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand stop took 188 milliseconds Mar 29 10:35:52 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand stop took 54 milliseconds Mar 29 10:35:52 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 10:35:52 volumio volumio[972]: info: Mar 29 10:35:52 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:52 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand status took 19 milliseconds Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand status took 16 milliseconds Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand clear took 16 milliseconds Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8000/stream" Mar 29 10:35:52 volumio volumio[972]: error: updateQueue error: null Mar 29 10:35:52 volumio volumio[972]: info: Mar 29 10:35:52 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:52 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:52 volumio volumio[972]: info: ------------------------------ 42ms Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand add "http://radio.linnrecords.com:8000/stream" took 23 milliseconds Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand play Mar 29 10:35:52 volumio volumio[972]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 29 10:35:52 volumio volumio[972]: info: ------------------------------ 89ms Mar 29 10:35:52 volumio volumio[972]: info: ------------------------------ 25ms Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand play took 7 milliseconds Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 10:35:52 volumio volumio[972]: info: Mar 29 10:35:52 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:52 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:52 volumio volumio[972]: info: Mar 29 10:35:52 volumio volumio[972]: ---------------------------- MPD announces system playlist update Mar 29 10:35:52 volumio volumio[972]: info: Ignoring MPD Status Update Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand status took 6 milliseconds Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:52 volumio volumio[972]: info: ------------------------------ 13ms Mar 29 10:35:52 volumio volumio[972]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 29 10:35:52 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:52 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:52 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:52 volumio volumio[972]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tommy Smith - Gnossienne No.1 [11]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Mar 29 10:35:52 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::syncState stateService play Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus stop Mar 29 10:35:52 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:52 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:52 volumio volumio[972]: info: ------------------------------ 56ms Mar 29 10:35:53 volumio smbd[8839]: [2024/03/29 10:35:53.127563, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 29 10:35:53 volumio smbd[8839]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 29 10:35:53 volumio smbd[8839]: [2024/03/29 10:35:53.136154, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 29 10:35:53 volumio smbd[8839]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 29 10:35:53 volumio smbd[8839]: [2024/03/29 10:35:53.167551, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 29 10:35:53 volumio smbd[8839]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 29 10:35:56 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 29 10:35:56 volumio volumio[972]: info: ControllerPersonalRadio::handleBrowseUri Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::ClearQueue Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::stop Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 10:35:58 volumio volumio[972]: verbose: UNSET VOLATILE Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::stPlaybackTimer Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::updateTrackBlock Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrackBlock Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::serviceStop Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::serviceStop Mar 29 10:35:58 volumio volumio[972]: info: ControllerMpd::stop Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::clearPlayQueue Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::saveQueue Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushQueue Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::addQueueItems Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::addQueueItems Mar 29 10:35:58 volumio volumio[972]: info: Adding Item to queue: webmbc/0 Mar 29 10:35:58 volumio volumio[972]: info: Exploding uri webmbc/0 in service personal_radio Mar 29 10:35:58 volumio volumio[972]: info: Mar 29 10:35:58 volumio volumio[972]: ---------------------------- MPD announces state update: player Mar 29 10:35:58 volumio volumio[972]: info: sendMpdCommand stop took 56 milliseconds Mar 29 10:35:58 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:58 volumio volumio[972]: info: ControllerMpd::getState Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand status Mar 29 10:35:58 volumio volumio[972]: info: sendMpdCommand status took 14 milliseconds Mar 29 10:35:58 volumio volumio[972]: info: sendMpdCommand status took 15 milliseconds Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::parseState Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 10:35:58 volumio volumio[972]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 29 10:35:58 volumio volumio[972]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:58 volumio volumio[972]: verbose: ControllerMpd::parseTrackInfo Mar 29 10:35:58 volumio volumio[972]: info: ControllerMpd::pushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::servicePushState Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: 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":"Tommy Smith - Gnossienne No.1 [11]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Mar 29 10:35:58 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::syncState stateService stop Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus stop Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: No code Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: 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":"Tommy Smith - Gnossienne No.1 [11]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""} Mar 29 10:35:58 volumio volumio[972]: verbose: CURRENT POSITION 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::syncState stateService stop Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::syncState currentStatus stop Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: No code Mar 29 10:35:58 volumio volumio[972]: info: CoreStateMachine::pushState Mar 29 10:35:58 volumio volumio[972]: info: CorePlayQueue::getTrack 0 Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::volumioPushState Mar 29 10:35:58 volumio volumio[972]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 10:35:58 volumio volumio[972]: info: ------------------------------ 151ms Mar 29 10:35:59 volumio volumio[972]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 10:35:59 volumio volumio[972]: TypeError: Cannot read property 'replace' of undefined Mar 29 10:35:59 volumio volumio[972]: at /data/plugins/music_service/personal_radio/index.js:499:45 Mar 29 10:35:59 volumio volumio[972]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:463:26) Mar 29 10:35:59 volumio volumio[972]: at Request.self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Mar 29 10:35:59 volumio volumio[972]: at emitTwo (events.js:126:13) Mar 29 10:35:59 volumio volumio[972]: at Request.emit (events.js:214:7) Mar 29 10:35:59 volumio volumio[972]: at Request. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:1044:10) Mar 29 10:35:59 volumio volumio[972]: at emitOne (events.js:116:13) Mar 29 10:35:59 volumio volumio[972]: at Request.emit (events.js:211:7) Mar 29 10:35:59 volumio volumio[972]: at IncomingMessage. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:965:12) Mar 29 10:35:59 volumio volumio[972]: at emitNone (events.js:111:20) Mar 29 10:35:59 volumio volumio[972]: at IncomingMessage.emit (events.js:208:7) Mar 29 10:35:59 volumio volumio[972]: at endReadableNT (_stream_readable.js:1064:12) Mar 29 10:35:59 volumio volumio[972]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Mar 29 10:35:59 volumio volumio[972]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 29 10:35:59 volumio volumio[972]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 10:36:01 volumio sudo[8862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 10:35 Mar 29 10:36:01 volumio sudo[8862]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"