Feb 07 10:16:02 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Feb 07 10:16:05 volumio volumio[1398]: info: Adding a new share Feb 07 10:16:05 volumio volumio[1398]: info: No correspondence found in configuration for share toshiba on IP 192.168.88.198 Feb 07 10:16:05 volumio volumio[1398]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.88.198 -N -m SMB3_11 Feb 07 10:16:05 volumio volumio[1398]: info: Negotiated SMB version with toshiba: SMB3_11 Feb 07 10:16:05 volumio volumio[1398]: info: Detected device toshiba with version SMB3_11 Feb 07 10:16:05 volumio volumio[1398]: info: Guest mount: forcing fallback SMB version 2.1 for toshiba Feb 07 10:16:05 volumio volumio[1398]: info: Set SMB version 3.11 in CIFS options: vers=2.1 Feb 07 10:16:05 volumio sudo[7303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.88.198/toshiba\\music /mnt/NAS/toshiba Feb 07 10:16:05 volumio sudo[7303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 10:16:05 volumio kernel: netfs: FS-Cache loaded Feb 07 10:16:05 volumio kernel: Key type cifs.spnego registered Feb 07 10:16:05 volumio kernel: Key type cifs.idmap registered Feb 07 10:16:05 volumio kernel: CIFS: Attempting to mount //192.168.88.198/toshiba/music Feb 07 10:16:05 volumio sudo[7303]: pam_unix(sudo:session): session closed for user root Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:05 volumio volumio[1398]: info: Feb 07 10:16:05 volumio volumio[1398]: ---------------------------- MPD announces state update: update Feb 07 10:16:05 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:05 volumio volumio[1398]: info: Feb 07 10:16:05 volumio volumio[1398]: ---------------------------- MPD announces state update: update Feb 07 10:16:05 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:05 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:05 volumio volumio[1398]: info: Command Router : Notfying DB Updatetrue Feb 07 10:16:05 volumio volumio[1398]: info: sendMpdCommand status took 106 milliseconds Feb 07 10:16:05 volumio volumio[1398]: info: sendMpdCommand status took 106 milliseconds Feb 07 10:16:05 volumio volumio[1398]: info: sendMpdCommand status took 106 milliseconds Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:05 volumio volumio[1398]: info: Command Router : Notfying DB Updatetrue Feb 07 10:16:05 volumio volumio[1398]: info: CoreCommandRouter::Close All Modals sent Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:05 volumio volumio[1398]: info: [1770448565787] Database update started Feb 07 10:16:05 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 07 10:16:05 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:05 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:05 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:05 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:05 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:05 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:05 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:05 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:05 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:05 volumio volumio[1398]: info: ------------------------------ 123ms Feb 07 10:16:05 volumio volumio[1398]: info: ------------------------------ 122ms Feb 07 10:16:06 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:06 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 07 10:16:10 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:14 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:18 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:26 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:30 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Feb 07 10:16:30 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:37 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 07 10:16:37 volumio volumio[1398]: info: [1770448597492] [80s80s] handleBrowseUri curUri: 80s80s Feb 07 10:16:37 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:16:38 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:38 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 07 10:16:39 volumio volumio[1398]: info: [1770448598999] [80s80s] handleBrowseUri curUri: 80s80s/nineties Feb 07 10:16:39 volumio volumio[1398]: info: [1770448598999] [80s80s] getRadioContent url: nineties Feb 07 10:16:39 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:16:40 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:16:40 volumio volumio[1398]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::ClearQueue Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::stop Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:40 volumio volumio[1398]: info: CorePlayQueue::clearPlayQueue Feb 07 10:16:40 volumio volumio[1398]: info: CorePlayQueue::saveQueue Feb 07 10:16:40 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::addQueueItems Feb 07 10:16:40 volumio volumio[1398]: info: CorePlayQueue::addQueueItems Feb 07 10:16:40 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:16:40 volumio volumio[1398]: info: Adding Item to queue: webnineties/0 Feb 07 10:16:40 volumio volumio[1398]: info: Exploding uri webnineties/0 in service 80s80s Feb 07 10:16:40 volumio volumio[1398]: info: [1770448600792] [80s80s] explodeUri: webnineties/0 Feb 07 10:16:40 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Feb 07 10:16:40 volumio volumio[1398]: info: CorePlayQueue::saveQueue Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Feb 07 10:16:40 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Feb 07 10:16:40 volumio volumio[1398]: info: CoreCommandRouter::volumioPlay Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::play index 0 Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::stop Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::play index undefined Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:40 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:40 volumio volumio[1398]: info: CoreStateMachine::startPlaybackTimer Feb 07 10:16:40 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:40 volumio volumio[1398]: info: [1770448600794] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/pop/mp3-192/volumio Feb 07 10:16:40 volumio volumio[1398]: info: [1770448600794] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=141&count=2 Feb 07 10:16:41 volumio volumio[1398]: info: [1770448601810] [80s80s] received new event containing 2 songs. Feb 07 10:16:41 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 10:16:41 volumio volumio[1398]: info: sendMpdCommand stop took 1 milliseconds Feb 07 10:16:41 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand clear Feb 07 10:16:41 volumio volumio[1398]: info: Feb 07 10:16:41 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:41 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:41 volumio volumio[1398]: info: sendMpdCommand clear took 0 milliseconds Feb 07 10:16:41 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 07 10:16:41 volumio volumio[1398]: info: Feb 07 10:16:41 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:41 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:41 volumio volumio[1398]: error: updateQueue error: null Feb 07 10:16:41 volumio volumio[1398]: error: updateQueue error: null Feb 07 10:16:41 volumio volumio[1398]: info: ------------------------------ 1ms Feb 07 10:16:41 volumio volumio[1398]: info: sendMpdCommand consume 1 took 1 milliseconds Feb 07 10:16:41 volumio volumio[1398]: info: ------------------------------ 0ms Feb 07 10:16:41 volumio volumio[1398]: info: [1770448601812] [80s80s] adding url: http://streams.90s90s.de/pop/mp3-192/volumio Feb 07 10:16:41 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" Feb 07 10:16:41 volumio volumio[1398]: info: Feb 07 10:16:41 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:41 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:41 volumio volumio[1398]: info: sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" took 1 milliseconds Feb 07 10:16:41 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand play Feb 07 10:16:41 volumio volumio[1398]: info: Feb 07 10:16:41 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:41 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:41 volumio volumio[1398]: info: ------------------------------ 4ms Feb 07 10:16:41 volumio volumio[1398]: info: sendMpdCommand play took 3 milliseconds Feb 07 10:16:41 volumio volumio[1398]: info: ------------------------------ 3ms Feb 07 10:16:41 volumio volumio[1398]: info: [1770448601817] [80s80s] Pushing the next song state: Massive Attack - Unfinished Sympathy Feb 07 10:16:41 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:41 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:41 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:41 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music124/v4/52/8a/9b/528a9bc8-b36b-e02c-295f-0d79d6b51a8d/source/600x600bb.jpg","name":"Massive Attack - Unfinished Sympathy","title":"Unfinished Sympathy","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"253","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 07 10:16:41 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:16:41 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:16:41 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus stop Feb 07 10:16:41 volumio volumio[1398]: info: [1770448601817] [80s80s] PlayNextTrack API delay: 30 Feb 07 10:16:41 volumio volumio[1398]: info: [1770448601818] [80s80s] Setting timer to: 253000 milliseconds. Feb 07 10:16:42 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:42 volumio volumio[1398]: info: CorePlayQueue::getTrack 1 Feb 07 10:16:42 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 23 milliseconds Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 22 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 25ms Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 24ms Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:43 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:43 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:43 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:43 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:43 volumio volumio[1398]: info: Feb 07 10:16:43 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 2 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 2 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 1ms Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 4ms Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 4ms Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 3ms Feb 07 10:16:43 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:43 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:43 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:43 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:43 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:43 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:43 volumio volumio[1398]: info: ------------------------------ 3ms Feb 07 10:16:47 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::ClearQueue Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::stop Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::stPlaybackTimer Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::serviceStop Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::serviceStop Feb 07 10:16:47 volumio volumio[1398]: info: ControllerMpd::stop Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::clearPlayQueue Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::saveQueue Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::addQueueItems Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::addQueueItems Feb 07 10:16:47 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:16:47 volumio volumio[1398]: info: Adding Item to queue: webnineties/0 Feb 07 10:16:47 volumio volumio[1398]: info: Using cached record of: webnineties/0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::saveQueue Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPlay Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::play index 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::stop Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::play index undefined Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::startPlaybackTimer Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: [1770448607419] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/pop/mp3-192/volumio Feb 07 10:16:47 volumio volumio[1398]: info: [1770448607419] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=141&count=2 Feb 07 10:16:47 volumio volumio[1398]: info: Feb 07 10:16:47 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand stop took 19 milliseconds Feb 07 10:16:47 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music124/v4/52/8a/9b/528a9bc8-b36b-e02c-295f-0d79d6b51a8d/source/600x600bb.jpg","name":"Massive Attack - Unfinished Sympathy","title":"Unfinished Sympathy","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"253","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 07 10:16:47 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::syncState stateService stop Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus stop Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:47 volumio volumio[1398]: info: No code Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand status took 4 milliseconds Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:47 volumio volumio[1398]: info: Feb 07 10:16:47 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:47 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand status took 3 milliseconds Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:47 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:47 volumio volumio[1398]: info: ------------------------------ 10ms Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:47 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:47 volumio volumio[1398]: info: ------------------------------ 7ms Feb 07 10:16:47 volumio volumio[1398]: info: [1770448607953] [80s80s] received new event containing 2 songs. Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand stop took 1 milliseconds Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand clear Feb 07 10:16:47 volumio volumio[1398]: info: Feb 07 10:16:47 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:47 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand clear took 1 milliseconds Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 07 10:16:47 volumio volumio[1398]: info: Feb 07 10:16:47 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:47 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:47 volumio volumio[1398]: error: updateQueue error: null Feb 07 10:16:47 volumio volumio[1398]: error: updateQueue error: null Feb 07 10:16:47 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand consume 1 took 1 milliseconds Feb 07 10:16:47 volumio volumio[1398]: info: ------------------------------ 1ms Feb 07 10:16:47 volumio volumio[1398]: info: [1770448607956] [80s80s] adding url: http://streams.90s90s.de/pop/mp3-192/volumio Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" Feb 07 10:16:47 volumio volumio[1398]: info: Feb 07 10:16:47 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:47 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" took 1 milliseconds Feb 07 10:16:47 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand play Feb 07 10:16:47 volumio volumio[1398]: info: Feb 07 10:16:47 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:47 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:47 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:47 volumio volumio[1398]: info: sendMpdCommand play took 2 milliseconds Feb 07 10:16:47 volumio volumio[1398]: info: ------------------------------ 1ms Feb 07 10:16:47 volumio volumio[1398]: info: [1770448607959] [80s80s] Pushing the next song state: Massive Attack - Unfinished Sympathy Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:47 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:47 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music124/v4/52/8a/9b/528a9bc8-b36b-e02c-295f-0d79d6b51a8d/source/600x600bb.jpg","name":"Massive Attack - Unfinished Sympathy","title":"Unfinished Sympathy","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"253","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 07 10:16:47 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:16:47 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus stop Feb 07 10:16:47 volumio volumio[1398]: info: [1770448607959] [80s80s] PlayNextTrack API delay: 30 Feb 07 10:16:47 volumio volumio[1398]: info: [1770448607960] [80s80s] Setting timer to: 253000 milliseconds. Feb 07 10:16:48 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:48 volumio volumio[1398]: info: CorePlayQueue::getTrack 1 Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand status took 11 milliseconds Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand status took 11 milliseconds Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:49 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:49 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:49 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:49 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:49 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:49 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 13ms Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 13ms Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:49 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:49 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:49 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:49 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:49 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:49 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 3ms Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:49 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:16:49 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:16:49 volumio volumio[1398]: info: Feb 07 10:16:49 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 1ms Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand status took 0 milliseconds Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:49 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:49 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:49 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:49 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:16:49 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:16:49 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:16:49 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:16:49 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:16:49 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 10:16:49 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:16:50 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:16:57 volumio volumio[1398]: info: CoreCommandRouter::volumioPlay Feb 07 10:16:57 volumio volumio[1398]: info: CoreStateMachine::play index undefined Feb 07 10:16:57 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:16:57 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:06 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 07 10:17:06 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 10:17:07 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:17:11 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 07 10:17:12 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:17:19 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::ClearQueue Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::stop Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::stPlaybackTimer Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::serviceStop Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::serviceStop Feb 07 10:17:19 volumio volumio[1398]: info: ControllerMpd::stop Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::clearPlayQueue Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::saveQueue Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::addQueueItems Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::addQueueItems Feb 07 10:17:19 volumio volumio[1398]: info: Preload queue cleared Feb 07 10:17:19 volumio volumio[1398]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::saveQueue Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPlay Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::play index 0 Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::stop Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::play index undefined Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::startPlaybackTimer Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: info: [1770448639067] ControllerWebradio::clearAddPlayTrack Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 10:17:19 volumio volumio[1398]: info: Feb 07 10:17:19 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand stop took 6 milliseconds Feb 07 10:17:19 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand stop took 2 milliseconds Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received 80s80s Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand clear Feb 07 10:17:19 volumio volumio[1398]: info: Feb 07 10:17:19 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:17:19 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand status took 3 milliseconds Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand clear took 2 milliseconds Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Feb 07 10:17:19 volumio volumio[1398]: info: Feb 07 10:17:19 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:19 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:19 volumio volumio[1398]: error: updateQueue error: null Feb 07 10:17:19 volumio volumio[1398]: info: ------------------------------ 1ms Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:19 volumio volumio[1398]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 07 10:17:19 volumio volumio[1398]: info: ------------------------------ 5ms Feb 07 10:17:19 volumio volumio[1398]: verbose: New Socket.io Connection to 192.168.88.128:3000 from 192.168.88.180 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 10:17:19 volumio volumio[1398]: info: Discovery: Getting this device information Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 10:17:19 volumio volumio[1398]: info: Feb 07 10:17:19 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:17:19 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 599 milliseconds Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand play Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand status took 603 milliseconds Feb 07 10:17:19 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:19 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:19 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:19 volumio volumio[1398]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 07 10:17:19 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::syncState stateService stop Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus stop Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:19 volumio volumio[1398]: info: No code Feb 07 10:17:19 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:19 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:19 volumio volumio[1398]: info: ------------------------------ 610ms Feb 07 10:17:19 volumio volumio[1398]: info: Feb 07 10:17:19 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:17:19 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:17:19 volumio volumio[1398]: info: ------------------------------ 12ms Feb 07 10:17:19 volumio volumio[1398]: info: sendMpdCommand play took 12 milliseconds Feb 07 10:17:19 volumio volumio[1398]: info: ------------------------------ 6ms Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 13 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 13 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 17521/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus stop Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 17521/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Feb 07 10:17:22 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 17521/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Feb 07 10:17:22 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 19ms Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 21ms Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 10ms Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 17521/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Feb 07 10:17:22 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 12ms Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:17:22 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:17:22 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 1ms Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:17:22 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Feb 07 10:17:22 volumio volumio[1398]: info: Ignoring MPD Status Update Feb 07 10:17:22 volumio volumio[1398]: info: Feb 07 10:17:22 volumio volumio[1398]: ---------------------------- MPD announces state update: player Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::getState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 2ms Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 2 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Feb 07 10:17:22 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 7ms Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 5ms Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 6 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 6ms Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand status took 5 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseState Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Feb 07 10:17:22 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 10ms Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 07 10:17:22 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Feb 07 10:17:22 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: ControllerMpd::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Feb 07 10:17:22 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Feb 07 10:17:22 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 07 10:17:22 volumio volumio[1398]: verbose: CURRENT POSITION 0 Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Feb 07 10:17:22 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreStateMachine::pushState Feb 07 10:17:22 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 16ms Feb 07 10:17:22 volumio volumio[1398]: info: ------------------------------ 16ms Feb 07 10:17:23 volumio volumio[1398]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 10:17:23 volumio volumio[1398]: Error: Client network socket disconnected before secure TLS connection was established Feb 07 10:17:23 volumio volumio[1398]: at connResetException (node:internal/errors:720:14) Feb 07 10:17:23 volumio volumio[1398]: at TLSSocket.onConnectEnd (node:_tls_wrap:1682:19) Feb 07 10:17:23 volumio volumio[1398]: at TLSSocket.emit (node:events:526:35) Feb 07 10:17:23 volumio volumio[1398]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 10:17:23 volumio volumio[1398]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 10:17:23 volumio volumio[1398]: code: 'ECONNRESET', Feb 07 10:17:23 volumio volumio[1398]: path: null, Feb 07 10:17:23 volumio volumio[1398]: host: 'cdn-images.dzcdn.net', Feb 07 10:17:23 volumio volumio[1398]: port: 443, Feb 07 10:17:23 volumio volumio[1398]: localAddress: undefined Feb 07 10:17:23 volumio volumio[1398]: } Feb 07 10:17:23 volumio volumio[1398]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 10:17:24 volumio sudo[7479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 10:16' Feb 07 10:17:24 volumio sudo[7479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"