Feb 09 22:18:04 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd Feb 09 22:18:04 volumio kernel: usb 2-1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00 Feb 09 22:18:04 volumio kernel: usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Feb 09 22:18:04 volumio kernel: usb 2-1: Product: ASMT105x Feb 09 22:18:04 volumio kernel: usb 2-1: Manufacturer: ASMT Feb 09 22:18:04 volumio kernel: usb 2-1: SerialNumber: 234567890126 Feb 09 22:18:04 volumio kernel: scsi host0: uas Feb 09 22:18:05 volumio kernel: scsi 0:0:0:0: Direct-Access ST932032 5AS 0 PQ: 0 ANSI: 6 Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: [sda] 625142448 512-byte logical blocks: (320 GB/298 GiB) Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes Feb 09 22:18:05 volumio kernel: sda: sda1 Feb 09 22:18:05 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Feb 09 22:18:05 volumio volumio[1243]: info: Mounting Device Mobile Feb 09 22:18:05 volumio sudo[4354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Mobile -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 09 22:18:05 volumio sudo[4354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 22:18:06 volumio ntfs-3g[4359]: Version 2022.10.3 integrated FUSE 28 Feb 09 22:18:06 volumio ntfs-3g[4359]: Mounted /dev/sda1 (Read-Write, label "Mobile", NTFS 3.1) Feb 09 22:18:06 volumio ntfs-3g[4359]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 09 22:18:06 volumio ntfs-3g[4359]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Feb 09 22:18:06 volumio ntfs-3g[4359]: Global ownership and permissions enforced, configuration type 7 Feb 09 22:18:06 volumio sudo[4354]: pam_unix(sudo:session): session closed for user root Feb 09 22:18:10 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::ClearQueue Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::stop Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::stPlaybackTimer Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::updateTrackBlock Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrackBlock Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::serviceStop Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::serviceStop Feb 09 22:18:10 volumio volumio[1243]: info: [1770664690872] ControllerWebradio::stop Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::clearPlayQueue Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::saveQueue Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::volumioPushQueue Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::addQueueItems Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::addQueueItems Feb 09 22:18:10 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:18:10 volumio volumio[1243]: info: Adding Item to queue: http://stream.radioparadise.com/world-etc-flacm Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::volumioPushQueue Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::saveQueue Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::updateTrackBlock Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrackBlock Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::volumioPlay Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::play index 0 Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::stop Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::play index undefined Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::startPlaybackTimer Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: [1770664690896] ControllerWebradio::clearAddPlayTrack Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 22:18:10 volumio volumio[1243]: info: Feb 09 22:18:10 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:10 volumio volumio[1243]: info: sendMpdCommand stop took 35 milliseconds Feb 09 22:18:10 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:10 volumio volumio[1243]: info: sendMpdCommand stop took 12 milliseconds Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 22:18:10 volumio volumio[1243]: info: Feb 09 22:18:10 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:10 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:10 volumio volumio[1243]: info: Feb 09 22:18:10 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:10 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:10 volumio volumio[1243]: info: Feb 09 22:18:10 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:10 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:10 volumio volumio[1243]: info: sendMpdCommand status took 9 milliseconds Feb 09 22:18:10 volumio volumio[1243]: info: sendMpdCommand clear took 8 milliseconds Feb 09 22:18:10 volumio volumio[1243]: info: sendMpdCommand status took 5 milliseconds Feb 09 22:18:10 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/world-etc-flacm" Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:10 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:10 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 22:18:10 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:10 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:10 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:10 volumio volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 22:18:10 volumio volumio[1243]: info: ------------------------------ 42ms Feb 09 22:18:10 volumio volumio[1243]: info: ------------------------------ 40ms Feb 09 22:18:10 volumio volumio[1243]: info: Feb 09 22:18:10 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:10 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:10 volumio volumio[1243]: info: Feb 09 22:18:10 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:10 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:10 volumio volumio[1243]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 09 22:18:11 volumio volumio[1243]: error: updateQueue error: null Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 299ms Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 296 milliseconds Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/world-etc-flacm" Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 310ms Feb 09 22:18:11 volumio volumio[1243]: error: updateQueue error: null Feb 09 22:18:11 volumio volumio[1243]: error: updateQueue error: null Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:11 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 263ms Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 263ms Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand add "http://stream.radioparadise.com/world-etc-flacm" took 2 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand play Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:11 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:11 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 3ms Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand play took 2 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 2ms Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 1ms Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 16 milliseconds Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 17 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 16 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 21ms Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 1 milliseconds Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 59ms Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 58ms Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 38 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 38 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 38 milliseconds Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 124ms Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 125ms Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 123ms Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:11 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:11 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:11 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:11 volumio volumio[1243]: info: Feb 09 22:18:11 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 5ms Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 3ms Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 3 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 3ms Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand status took 3 milliseconds Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 22:18:11 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:11 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:11 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:11 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 59ms Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 59ms Feb 09 22:18:11 volumio volumio[1243]: info: ------------------------------ 59ms Feb 09 22:18:14 volumio volumio[1243]: info: Feb 09 22:18:14 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:14 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:14 volumio volumio[1243]: info: Feb 09 22:18:14 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:14 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:14 volumio volumio[1243]: info: Feb 09 22:18:14 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:14 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:14 volumio volumio[1243]: info: Feb 09 22:18:14 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:14 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:14 volumio volumio[1243]: info: Feb 09 22:18:14 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:14 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:14 volumio volumio[1243]: info: Feb 09 22:18:14 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:14 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:14 volumio volumio[1243]: info: ------------------------------ 4ms Feb 09 22:18:14 volumio volumio[1243]: info: sendMpdCommand status took 3 milliseconds Feb 09 22:18:14 volumio volumio[1243]: info: ------------------------------ 3ms Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:14 volumio volumio[1243]: info: sendMpdCommand status took 5 milliseconds Feb 09 22:18:14 volumio volumio[1243]: info: ------------------------------ 5ms Feb 09 22:18:14 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:14 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:14 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:14 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:14 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3622,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La Perla - El sol","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:14 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:14 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:14 volumio volumio[1243]: info: ------------------------------ 29ms Feb 09 22:18:14 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 09 22:18:14 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:14 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:14 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:14 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:14 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3622,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La Perla - El sol","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:14 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:14 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:14 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:14 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:14 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3622,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La Perla - El sol","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:14 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:14 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:14 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:14 volumio volumio[1243]: info: ------------------------------ 67ms Feb 09 22:18:14 volumio volumio[1243]: info: ------------------------------ 66ms Feb 09 22:18:29 volumio ntpd[1025]: PROTO: 188.191.80.88 unlink local addr 192.168.1.79 -> Feb 09 22:18:39 volumio volumio[1243]: info: VolumeController::SetAlsaVolume100 Feb 09 22:18:39 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:39 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:39 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:41 volumio volumio[1243]: info: VolumeController::SetAlsaVolume0 Feb 09 22:18:41 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:41 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:41 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:43 volumio volumio[1243]: info: VolumeController::SetAlsaVolume29 Feb 09 22:18:43 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:43 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:43 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:49 volumio volumio[1243]: info: CoreCommandRouter::volumioGetState Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::volumioStop Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::stop Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::stPlaybackTimer Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::updateTrackBlock Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrackBlock Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::serviceStop Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::serviceStop Feb 09 22:18:50 volumio volumio[1243]: info: [1770664730220] ControllerWebradio::stop Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 22:18:50 volumio volumio[1243]: info: Feb 09 22:18:50 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:50 volumio volumio[1243]: info: sendMpdCommand stop took 22 milliseconds Feb 09 22:18:50 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:50 volumio volumio[1243]: info: Feb 09 22:18:50 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:50 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:50 volumio volumio[1243]: info: Feb 09 22:18:50 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:50 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:50 volumio volumio[1243]: info: sendMpdCommand status took 6 milliseconds Feb 09 22:18:50 volumio volumio[1243]: info: sendMpdCommand status took 6 milliseconds Feb 09 22:18:50 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:50 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:50 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 22:18:50 volumio volumio[1243]: info: ------------------------------ 25ms Feb 09 22:18:50 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 09 22:18:50 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:50 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:50 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 22:18:50 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:50 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:50 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:50 volumio volumio[1243]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 09 22:18:50 volumio volumio[1243]: info: ------------------------------ 53ms Feb 09 22:18:50 volumio volumio[1243]: info: ------------------------------ 52ms Feb 09 22:18:50 volumio volumio[1243]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPlay Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::play index undefined Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::startPlaybackTimer Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: [1770664732206] ControllerWebradio::clearAddPlayTrack Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand stop took 0 milliseconds Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand clear took 0 milliseconds Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/world-etc-flacm" Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: error: updateQueue error: null Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 2ms Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/world-etc-flacm" Feb 09 22:18:52 volumio volumio[1243]: error: updateQueue error: null Feb 09 22:18:52 volumio volumio[1243]: error: updateQueue error: null Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 157ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 157ms Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand add "http://stream.radioparadise.com/world-etc-flacm" took 1 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand play Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand play took 3 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 3ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 1ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 2ms Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 16 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 16 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 15 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 2 milliseconds Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 36ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 57ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 57ms Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 105ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 102ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 100ms Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:52 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:52 volumio volumio[1243]: info: Feb 09 22:18:52 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 9ms Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 8 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 7ms Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 7 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 6ms Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand status took 6 milliseconds Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 22:18:52 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:52 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:52 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:52 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 61ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 61ms Feb 09 22:18:52 volumio volumio[1243]: info: ------------------------------ 60ms Feb 09 22:18:55 volumio volumio[1243]: info: Feb 09 22:18:55 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:55 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:55 volumio volumio[1243]: info: Feb 09 22:18:55 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:55 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:55 volumio volumio[1243]: info: Feb 09 22:18:55 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:55 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:55 volumio volumio[1243]: info: Feb 09 22:18:55 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:55 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:55 volumio volumio[1243]: info: Feb 09 22:18:55 volumio volumio[1243]: ---------------------------- MPD announces system playlist update Feb 09 22:18:55 volumio volumio[1243]: info: Ignoring MPD Status Update Feb 09 22:18:55 volumio volumio[1243]: info: Feb 09 22:18:55 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:18:55 volumio volumio[1243]: info: ControllerMpd::getState Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Feb 09 22:18:55 volumio volumio[1243]: info: ------------------------------ 3ms Feb 09 22:18:55 volumio volumio[1243]: info: sendMpdCommand status took 3 milliseconds Feb 09 22:18:55 volumio volumio[1243]: info: ------------------------------ 2ms Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:55 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:55 volumio volumio[1243]: info: ------------------------------ 3ms Feb 09 22:18:55 volumio volumio[1243]: info: sendMpdCommand status took 4 milliseconds Feb 09 22:18:55 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::parseState Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:55 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:55 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:55 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3622,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La Perla - El sol","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:55 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:55 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:55 volumio volumio[1243]: info: ------------------------------ 28ms Feb 09 22:18:55 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 09 22:18:55 volumio volumio[1243]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:55 volumio volumio[1243]: verbose: ControllerMpd::parseTrackInfo Feb 09 22:18:55 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:55 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:55 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3622,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La Perla - El sol","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:55 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:55 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:55 volumio volumio[1243]: info: ControllerMpd::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::servicePushState Feb 09 22:18:55 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:18:55 volumio volumio[1243]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3622,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La Perla - El sol","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Feb 09 22:18:55 volumio volumio[1243]: verbose: CURRENT POSITION 0 Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::syncState stateService play Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::syncState currentStatus play Feb 09 22:18:55 volumio volumio[1243]: info: Received an update from plugin. extracting info from payload Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:55 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:18:55 volumio volumio[1243]: info: ------------------------------ 73ms Feb 09 22:18:55 volumio volumio[1243]: info: ------------------------------ 73ms Feb 09 22:18:58 volumio volumio[1243]: info: VolumeController::SetAlsaVolume100 Feb 09 22:18:58 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:18:58 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:18:58 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:19:01 volumio volumio[1243]: info: VolumeController::SetAlsaVolume95 Feb 09 22:19:01 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:19:01 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:19:01 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:19:02 volumio volumio[1243]: info: VolumeController::SetAlsaVolume87 Feb 09 22:19:02 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:19:02 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:19:02 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:19:02 volumio volumio[1243]: info: VolumeController::SetAlsaVolume86 Feb 09 22:19:02 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:19:02 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:19:02 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:19:03 volumio volumio[1243]: info: VolumeController::SetAlsaVolume100 Feb 09 22:19:03 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:19:03 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:19:03 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:19:20 volumio ntpd[1025]: PROTO: 192.36.143.130 unlink local addr 192.168.1.79 -> Feb 09 22:19:21 volumio ntpd[1025]: PROTO: 162.159.200.1 unlink local addr 192.168.1.79 -> Feb 09 22:19:22 volumio volumio[1243]: info: CoreCommandRouter::volumioGetState Feb 09 22:19:26 volumio ntpd[1025]: PROTO: 91.244.115.121 unlink local addr 192.168.1.79 -> Feb 09 22:19:32 volumio ntpd[1025]: PROTO: 51.250.53.172 unlink local addr 192.168.1.79 -> Feb 09 22:19:33 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 09 22:19:33 volumio volumio[1243]: info: CURURI: music-library Feb 09 22:19:33 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:19:34 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 09 22:19:34 volumio volumio[1243]: info: CURURI: music-library/USB Feb 09 22:19:34 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:19:35 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 09 22:19:35 volumio volumio[1243]: info: CURURI: music-library/USB/Mobile Feb 09 22:19:35 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:19:36 volumio ntpd[1025]: PROTO: 195.161.69.241 unlink local addr 192.168.1.79 -> Feb 09 22:19:37 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 09 22:19:37 volumio volumio[1243]: info: CURURI: music-library/USB/Mobile/Music Feb 09 22:19:37 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:19:37 volumio volumio[1243]: info: Preloading song: music-library/USB/Mobile/Music/05. In The Army Now.dsf Feb 09 22:19:37 volumio volumio[1243]: info: Preloading song: music-library/USB/Mobile/Music/01. 30 лет.wav Feb 09 22:19:37 volumio volumio[1243]: info: Preloading song: music-library/USB/Mobile/Music/10 - Riders On The Storm.dsf Feb 09 22:19:37 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/05. In The Army Now.dsf in service mpd Feb 09 22:19:37 volumio volumio[1243]: info: ALBUMART /albumart?cacheid=947&web=//extralarge&path=%2Fmnt%2FUSB%2FMobile%2FMusic%2F05.%20In%20The%20Army%20Now.dsf&metadata=false Feb 09 22:19:37 volumio volumio[1243]: info: URI /mnt/USB/Mobile/Music/05. In The Army Now.dsf Feb 09 22:19:37 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/01. 30 лет.wav in service mpd Feb 09 22:19:37 volumio volumio[1243]: info: ALBUMART /albumart?cacheid=947&web=%3F%3F%3F%3F%3F%3F%20%3F%3F%3F%3F/%3F%3F%3F%3F%3F%20%3F%3F%20%3F%3F%3F/extralarge&path=%2Fmnt%2FUSB%2FMobile%2FMusic%2F01.%2030%20%D0%BB%D0%B5%D1%82.wav&metadata=false Feb 09 22:19:37 volumio volumio[1243]: info: URI /mnt/USB/Mobile/Music/01. 30 лет.wav Feb 09 22:19:37 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/10 - Riders On The Storm.dsf in service mpd Feb 09 22:19:37 volumio volumio[1243]: info: ALBUMART /albumart?cacheid=947&web=The%20Doors/L.a.Woman/extralarge&path=%2Fmnt%2FUSB%2FMobile%2FMusic%2F10%20-%20Riders%20On%20The%20Storm.dsf&metadata=false Feb 09 22:19:37 volumio volumio[1243]: info: URI /mnt/USB/Mobile/Music/10 - Riders On The Storm.dsf Feb 09 22:19:38 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:19:38 volumio volumio[1243]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::ClearQueue Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::stop Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::stPlaybackTimer Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::updateTrackBlock Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::getTrackBlock Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::pushState Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:19:38 volumio volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 22:19:38 volumio volumio[1243]: info: CoreCommandRouter::volumioPushState Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::serviceStop Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::getTrack 0 Feb 09 22:19:38 volumio volumio[1243]: info: CoreCommandRouter::serviceStop Feb 09 22:19:38 volumio volumio[1243]: info: [1770664778305] ControllerWebradio::stop Feb 09 22:19:38 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::clearPlayQueue Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::saveQueue Feb 09 22:19:38 volumio volumio[1243]: info: CoreCommandRouter::volumioPushQueue Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::addQueueItems Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::addQueueItems Feb 09 22:19:38 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/05. In The Army Now.dsf Feb 09 22:19:38 volumio volumio[1243]: info: Using cached record of: music-library/USB/Mobile/Music/05. In The Army Now.dsf Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/01. 30 лет.wav Feb 09 22:19:38 volumio volumio[1243]: info: Using cached record of: music-library/USB/Mobile/Music/01. 30 лет.wav Feb 09 22:19:38 volumio volumio[1243]: info: CoreCommandRouter::volumioPushQueue Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::saveQueue Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::updateTrackBlock Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::getTrackBlock Feb 09 22:19:38 volumio volumio[1243]: info: CoreCommandRouter::volumioPlay Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::play index 1 Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:19:38 volumio volumio[1243]: info: CoreStateMachine::addQueueItems Feb 09 22:19:38 volumio volumio[1243]: info: CorePlayQueue::addQueueItems Feb 09 22:19:38 volumio volumio[1243]: info: Preload queue cleared Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/10 - Riders On The Storm.dsf Feb 09 22:19:38 volumio volumio[1243]: info: Using cached record of: music-library/USB/Mobile/Music/10 - Riders On The Storm.dsf Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Guns n’ Roses - Discography Feb 09 22:19:38 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Guns n’ Roses - Discography in service mpd Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Metallica - Metallica [24-96] Feb 09 22:19:38 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Metallica - Metallica [24-96] in service mpd Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Metallica 1991 'Metallica' [Fr Vertigo 510 022-2] Feb 09 22:19:38 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Metallica 1991 'Metallica' [Fr Vertigo 510 022-2] in service mpd Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Nirvana - Nevermind - (1991) {DGC DGCD-24425} Feb 09 22:19:38 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Nirvana - Nevermind - (1991) {DGC DGCD-24425} in service mpd Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Russian Collection - Limited Edition Feb 09 22:19:38 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Russian Collection - Limited Edition in service mpd Feb 09 22:19:38 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Therion [restored] Feb 09 22:19:38 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Therion [restored] in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 60-х Часть I (2CD) © 2009 Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 60-х Часть I (2CD) © 2009 in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 60-х Часть II (2CD) © 2009 Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 60-х Часть II (2CD) © 2009 in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 70-х Часть I (2CD) © 2009 Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 70-х Часть I (2CD) © 2009 in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 70-х Часть II (2CD) © 2009 Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 70-х Часть II (2CD) © 2009 in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 70-х Часть III (2CD) © 2009 Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/VA - Русская Коллекция ХИТЫ 70-х Часть III (2CD) © 2009 in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/VA - Хиты 80-х - Как молоды мы были (ч.1) Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/VA - Хиты 80-х - Как молоды мы были (ч.1) in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Кипелов - 2022 - Легенды русского рока. КИПЕЛОВ (24bit-44.1kHz) Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Кипелов - 2022 - Легенды русского рока. КИПЕЛОВ (24bit-44.1kHz) in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Леонид Утесов - Записи 30-40 годов - Remastered [2022] Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Леонид Утесов - Записи 30-40 годов - Remastered [2022] in service mpd Feb 09 22:19:39 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Лучшие мелодии на свете (2007) - Золотые хиты 50-60-х (3CD) Feb 09 22:19:39 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Лучшие мелодии на свете (2007) - Золотые хиты 50-60-х (3CD) in service mpd Feb 09 22:19:40 volumio volumio[1243]: info: Adding Item to queue: music-library/USB/Mobile/Music/Лучшие мелодии на свете (2007) - Музыка для отдыха (3CD) Feb 09 22:19:40 volumio volumio[1243]: info: Exploding uri music-library/USB/Mobile/Music/Лучшие мелодии на свете (2007) - Музыка для отдыха (3CD) in service mpd Feb 09 22:19:40 volumio volumio[1243]: info: CoreStateMachine::stop Feb 09 22:19:40 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:19:40 volumio volumio[1243]: info: CoreStateMachine::play index undefined Feb 09 22:19:40 volumio volumio[1243]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 22:19:40 volumio volumio[1243]: info: CorePlayQueue::getTrack 1 Feb 09 22:19:40 volumio volumio[1243]: info: CoreStateMachine::startPlaybackTimer Feb 09 22:19:40 volumio volumio[1243]: info: CorePlayQueue::getTrack 1 Feb 09 22:19:40 volumio volumio[1243]: verbose: ControllerMpd::clearAddPlayTracks USB/Mobile/Music/01. 30 лет.wav Feb 09 22:19:40 volumio volumio[1243]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 22:19:40 volumio volumio[1243]: info: Feb 09 22:19:40 volumio volumio[1243]: ---------------------------- MPD announces state update: player Feb 09 22:19:40 volumio volumio[1243]: info: ALBUMART /albumart?cacheid=947&web=//extralarge&path=%2Fmnt%2FUSB%2FMobile%2FMusic%2FGuns%20n%E2%80%99%20Roses%20-%20Discography%2F1987%20-%20Appetite%20For%20Destruction%20(Geffen%2C%20GED%2024148%2C%20GEFD%2024148%2C%20Germany)%2FGuns%20n%E2%80%99%20Roses%20-%20Appetite%20For%20Destruction.flac&metadata=false Feb 09 22:19:40 volumio volumio[1243]: info: URI /mnt/USB/Mobile/Music/Guns n’ Roses - Discography/1987 - Appetite For Destruction (Geffen, GED 24148, GEFD 24148, Germany)/Guns n’ Roses - Appetite For Destruction.flac Feb 09 22:19:40 volumio volumio[1243]: info: ALBUMART /albumart?cacheid=947&web=Guns%20n'%20Roses/EP/extralarge&path=%2Fmnt%2FUSB%2FMobile%2FMusic%2FGuns%20n%E2%80%99%20Roses%20-%20Discography%2F1987%20-%20EP%20(Geffen%2C%2025XD-977%2C%20Japan)%2FGuns%20n'%20Roses%20-%20EP.cue&metadata=false Feb 09 22:19:40 volumio volumio[1243]: info: URI /mnt/USB/Mobile/Music/Guns n’ Roses - Discography/1987 - EP (Geffen, 25XD-977, Japan)/Guns n' Roses - EP.cue Feb 09 22:19:40 volumio volumio[1243]: info: ALBUMART /albumart?cacheid=947&web=Guns%20n'%20Roses/EP/extralarge&path=%2Fmnt%2FUSB%2FMobile%2FMusic%2FGuns%20n%E2%80%99%20Roses%20-%20Discography%2F1987%20-%20EP%20(Geffen%2C%2025XD-977%2C%20Japan)%2FGuns%20n'%20Roses%20-%20EP.cue&metadata=false Feb 09 22:19:40 volumio volumio[1243]: info: URI /mnt/USB/Mobile/Music/Guns n’ Roses - Discography/1987 - EP (Geffen, 25XD-977, Japan)/Guns n' Roses - EP.cue Feb 09 22:19:40 volumio volumio[1243]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 22:19:40 volumio volumio[1243]: Error: Unable to resolve or reject the same promise twice Feb 09 22:19:40 volumio volumio[1243]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 09 22:19:40 volumio volumio[1243]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Feb 09 22:19:40 volumio volumio[1243]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 09 22:19:40 volumio volumio[1243]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 09 22:19:40 volumio volumio[1243]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 09 22:19:40 volumio volumio[1243]: at Socket.emit (node:events:514:28) Feb 09 22:19:40 volumio volumio[1243]: at addChunk (node:internal/streams/readable:343:12) Feb 09 22:19:40 volumio volumio[1243]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 09 22:19:40 volumio volumio[1243]: at Readable.push (node:internal/streams/readable:253:10) Feb 09 22:19:40 volumio volumio[1243]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 09 22:19:40 volumio volumio[1243]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 22:19:41 volumio sudo[4716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 22:18' Feb 09 22:19:41 volumio sudo[4716]: 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"