-- Logs begin at Fri 2025-07-18 09:39:32 CST, end at Fri 2025-07-18 09:52:49 CST. -- Jul 18 09:51:02 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:51:06 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:51:10 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:51:10 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:51:10 volumio volumio[795]: info: Discovery: Getting this device information Jul 18 09:51:10 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Jul 18 09:51:10 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:51:10 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:51:12 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Jul 18 09:51:18 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 18 09:51:18 volumio volumio[795]: info: CURURI: music-library Jul 18 09:51:18 volumio volumio[795]: info: Preload queue cleared Jul 18 09:51:27 volumio kernel: usb 5-4: USB disconnect, device number 2 Jul 18 09:51:27 volumio volumio[795]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:16.2/usb5/5-4/5-4:1.0/host0/target0:0:0/0:0:0:1/block/sdc/sdc1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-Generic_MassStorageClass_000000000001-0:1-part1 /dev/disk/by-uuid/D670-69EC /dev/disk/by-path/pci-0000:00:16.2-usb-0:4:1.0-scsi-0:0:0:1-part1 /dev/disk/by-partuuid/acfa01d7-01","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/pci0000:00/0000:00:16.2/usb5/5-4/5-4:1.0/host0/target0:0:0/0:0:0:1/block/sdc/sdc1","DEVTYPE":"partition","DISKSEQ":"11","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"D670-69EC","ID_FS_UUID_ENC":"D670-69EC","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:1","ID_MODEL":"MassStorageClass","ID_MODEL_ENC":"MassStorageClass","ID_MODEL_ID":"0749","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"62330880","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"acfa01d7-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"acfa01d7","ID_PATH":"pci-0000:00:16.2-usb-0:4:1.0-scsi-0:0:0:1","ID_PATH_TAG":"pci-0000_00_16_2-usb-0_4_1_0-scsi-0_0_0_1","ID_REVISION":"TG01","ID_SERIAL":"Generic_MassStorageClass_000000000001-0:1","ID_SERIAL_SHORT":"000000000001","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Generic","ID_VENDOR_ENC":"Generic\\x20","ID_VENDOR_ID":"05e3","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"2115","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"29335202"} Jul 18 09:51:27 volumio sudo[3742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdc1 Jul 18 09:51:27 volumio sudo[3742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:51:27 volumio systemd[1]: media-D670\x2d69EC.mount: Succeeded. Jul 18 09:51:27 volumio sudo[3742]: pam_unix(sudo:session): session closed for user root Jul 18 09:51:31 volumio kernel: usb 3-4: new high-speed USB device number 2 using ehci-pci Jul 18 09:51:31 volumio kernel: usb 3-4: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice= 0.01 Jul 18 09:51:31 volumio kernel: usb 3-4: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Jul 18 09:51:31 volumio kernel: usb 3-4: Product: USB3.0 Card Reader Jul 18 09:51:31 volumio kernel: usb 3-4: Manufacturer: Generic Jul 18 09:51:31 volumio kernel: usb 3-4: SerialNumber: 000000000001 Jul 18 09:51:31 volumio kernel: usb-storage 3-4:1.0: USB Mass Storage device detected Jul 18 09:51:31 volumio kernel: scsi host0: usb-storage 3-4:1.0 Jul 18 09:51:31 volumio volumio[795]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/D670-69EC Jul 18 09:51:32 volumio kernel: scsi 0:0:0:0: Direct-Access Generic MassStorageClass TG01 PQ: 0 ANSI: 6 Jul 18 09:51:32 volumio kernel: scsi 0:0:0:1: Direct-Access Generic MassStorageClass TG01 PQ: 0 ANSI: 6 Jul 18 09:51:32 volumio kernel: sd 0:0:0:0: Attached scsi generic sg1 type 0 Jul 18 09:51:32 volumio kernel: scsi 0:0:0:1: Attached scsi generic sg2 type 0 Jul 18 09:51:32 volumio volumio[795]: info: Scanning removed location : "USB/D670-69EC" Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: info: Jul 18 09:51:32 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:32 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: info: Jul 18 09:51:32 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:32 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: info: Jul 18 09:51:32 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:32 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: info: Jul 18 09:51:32 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:32 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 18 milliseconds Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 17 milliseconds Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 15 milliseconds Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 16 milliseconds Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 15 milliseconds Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 13 milliseconds Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 11 milliseconds Jul 18 09:51:32 volumio volumio[795]: info: sendMpdCommand status took 9 milliseconds Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio kernel: sd 0:0:0:0: [sdb] Media removed, stopped polling Jul 18 09:51:32 volumio kernel: sd 0:0:0:0: [sdb] Attached SCSI removable disk Jul 18 09:51:32 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:32 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:32 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:32 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:32 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio volumio[795]: info: Jul 18 09:51:33 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand playlistinfo took 310 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand playlistinfo took 262 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand playlistinfo took 205 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand playlistinfo took 154 milliseconds Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:33 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:33 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:33 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:33 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:33 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:33 volumio kernel: sd 0:0:0:1: [sdc] 62333952 512-byte logical blocks: (31.9 GB/29.7 GiB) Jul 18 09:51:33 volumio kernel: sd 0:0:0:1: [sdc] Write Protect is off Jul 18 09:51:33 volumio kernel: sd 0:0:0:1: [sdc] Mode Sense: 21 00 00 00 Jul 18 09:51:33 volumio kernel: sd 0:0:0:1: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Jul 18 09:51:33 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:33 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:33 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:33 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:33 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:33 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:33 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:33 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:33 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:33 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:33 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:33 volumio volumio[795]: info: ------------------------------ 699ms Jul 18 09:51:33 volumio volumio[795]: info: ------------------------------ 697ms Jul 18 09:51:33 volumio volumio[795]: info: ------------------------------ 696ms Jul 18 09:51:33 volumio volumio[795]: info: ------------------------------ 693ms Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio volumio[795]: info: Jul 18 09:51:33 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio kernel: sdc: sdc1 Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio volumio[795]: info: Jul 18 09:51:33 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:33 volumio kernel: sd 0:0:0:1: [sdc] Attached SCSI removable disk Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio volumio[795]: info: Jul 18 09:51:33 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 474 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 468 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 172 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 170 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 169 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 167 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 166 milliseconds Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand status took 167 milliseconds Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:33 volumio volumio[795]: info: sendMpdCommand playlistinfo took 271 milliseconds Jul 18 09:51:33 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:33 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:33 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:33 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:33 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:33 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:33 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:51:33 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: ------------------------------ 954ms Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand playlistinfo took 342 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand playlistinfo took 301 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand playlistinfo took 254 milliseconds Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:34 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:34 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:34 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63146,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:34 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:34 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:34 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:34 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63146,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:34 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:34 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:34 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:34 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63146,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:34 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:34 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:34 volumio volumio[795]: info: ------------------------------ 964ms Jul 18 09:51:34 volumio volumio[795]: info: ------------------------------ 961ms Jul 18 09:51:34 volumio volumio[795]: info: ------------------------------ 957ms Jul 18 09:51:34 volumio volumio[795]: info: Mounting Device D670-69EC Jul 18 09:51:34 volumio sudo[3792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/D670-69EC -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 18 09:51:34 volumio sudo[3792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:51:34 volumio kernel: FAT-fs (sdc1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 18 09:51:34 volumio kernel: FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 18 09:51:34 volumio sudo[3792]: pam_unix(sudo:session): session closed for user root Jul 18 09:51:34 volumio volumio[795]: info: Scanning new location : "USB/D670-69EC" Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:34 volumio volumio[795]: info: Jul 18 09:51:34 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:34 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:34 volumio volumio[795]: info: Jul 18 09:51:34 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:34 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:34 volumio volumio[795]: info: Jul 18 09:51:34 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:34 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:34 volumio volumio[795]: info: Jul 18 09:51:34 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:51:34 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 17 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 16 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 15 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 14 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 13 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 13 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 10 milliseconds Jul 18 09:51:34 volumio volumio[795]: info: sendMpdCommand status took 10 milliseconds Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: info: Command Router : Notfying DB Updatetrue Jul 18 09:51:34 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:51:34 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:51:35 volumio volumio[795]: info: sendMpdCommand playlistinfo took 203 milliseconds Jul 18 09:51:35 volumio volumio[795]: info: sendMpdCommand playlistinfo took 162 milliseconds Jul 18 09:51:35 volumio volumio[795]: info: sendMpdCommand playlistinfo took 120 milliseconds Jul 18 09:51:35 volumio volumio[795]: info: sendMpdCommand playlistinfo took 80 milliseconds Jul 18 09:51:35 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:35 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:35 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:35 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:51:35 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:35 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:35 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64535,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:35 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:35 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:35 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:35 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64535,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:35 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:35 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:35 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:35 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64535,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:35 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:35 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:51:35 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:51:35 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64535,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:51:35 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:51:35 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:51:35 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:51:35 volumio volumio[795]: info: ------------------------------ 515ms Jul 18 09:51:35 volumio volumio[795]: info: ------------------------------ 513ms Jul 18 09:51:35 volumio volumio[795]: info: ------------------------------ 511ms Jul 18 09:51:35 volumio volumio[795]: info: ------------------------------ 508ms Jul 18 09:51:40 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:51:40 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:51:40 volumio volumio[795]: info: Discovery: Getting this device information Jul 18 09:51:40 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Jul 18 09:51:40 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:51:51 volumio ntpd[784]: 95.111.202.5 local addr 192.168.10.41 -> Jul 18 09:52:10 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:52:10 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:52:10 volumio volumio[795]: info: Discovery: Getting this device information Jul 18 09:52:10 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Jul 18 09:52:10 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:52:18 volumio volumio[795]: info: MPD Database updated - AlbumList cache refreshed Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: database Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: MPD Database updated - AlbumList cache refreshed Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: database Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: MPD Database updated - AlbumList cache refreshed Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: database Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: MPD Database updated - AlbumList cache refreshed Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: database Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: Jul 18 09:52:18 volumio volumio[795]: ---------------------------- MPD announces state update: update Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:18 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:18 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:21 volumio ntpd[784]: 162.159.200.123 local addr 192.168.10.41 -> Jul 18 09:52:21 volumio volumio[795]: Cannot compose Albumart path Jul 18 09:52:22 volumio volumio[795]: info: sendMpdCommand status took 4094 milliseconds Jul 18 09:52:22 volumio volumio[795]: info: sendMpdCommand status took 4094 milliseconds Jul 18 09:52:22 volumio volumio[795]: info: sendMpdCommand status took 4094 milliseconds Jul 18 09:52:22 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:22 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:52:22 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:52:22 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:22 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:22 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:22 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:24 volumio volumio[795]: Cannot compose Albumart path Jul 18 09:52:25 volumio volumio[795]: info: sendMpdCommand status took 6895 milliseconds Jul 18 09:52:25 volumio volumio[795]: info: sendMpdCommand status took 6893 milliseconds Jul 18 09:52:25 volumio volumio[795]: info: sendMpdCommand status took 6891 milliseconds Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:25 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:52:25 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:25 volumio volumio[795]: info: Jul 18 09:52:25 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:25 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:25 volumio volumio[795]: info: Jul 18 09:52:25 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:25 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:25 volumio volumio[795]: info: Jul 18 09:52:25 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:25 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:25 volumio volumio[795]: info: Jul 18 09:52:25 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:25 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:25 volumio volumio[795]: info: Jul 18 09:52:25 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:25 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:25 volumio volumio[795]: info: Jul 18 09:52:25 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:25 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:25 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:26 volumio volumio[795]: Cannot compose Albumart path Jul 18 09:52:27 volumio volumio[795]: info: sendMpdCommand status took 9282 milliseconds Jul 18 09:52:27 volumio volumio[795]: info: sendMpdCommand status took 9280 milliseconds Jul 18 09:52:27 volumio volumio[795]: info: sendMpdCommand status took 9278 milliseconds Jul 18 09:52:27 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:27 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:52:27 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:52:27 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:27 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:27 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:27 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:29 volumio volumio[795]: Cannot compose Albumart path Jul 18 09:52:29 volumio volumio[795]: info: sendMpdCommand status took 11697 milliseconds Jul 18 09:52:29 volumio volumio[795]: info: sendMpdCommand status took 11696 milliseconds Jul 18 09:52:29 volumio volumio[795]: info: sendMpdCommand status took 11693 milliseconds Jul 18 09:52:29 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:29 volumio volumio[795]: info: Command Router : Notfying DB Updatefalse Jul 18 09:52:29 volumio volumio[795]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:52:29 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:29 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:29 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:29 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:29 volumio volumio[795]: info: sendMpdCommand playlistinfo took 7649 milliseconds Jul 18 09:52:29 volumio volumio[795]: info: sendMpdCommand playlistinfo took 7649 milliseconds Jul 18 09:52:29 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:29 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:29 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:29 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:29 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:29 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:29 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:29 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:29 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:29 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:29 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:29 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:29 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:52:29 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108280,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hitomi Kuroishi - Innocent Days - [Code Geass]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 11961ms Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 11961ms Jul 18 09:52:30 volumio volumio[795]: info: Jul 18 09:52:30 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:30 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:30 volumio volumio[795]: info: Jul 18 09:52:30 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 5051 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 5050 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 5038ms Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand status took 5033 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 5033ms Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand status took 5031 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 5030ms Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand status took 5029 milliseconds Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108554,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108554,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 12127ms Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 12126ms Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 2835 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 2834 milliseconds Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108929,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108929,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 12299ms Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 12301ms Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 555 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 556 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 341ms Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand status took 339 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 334 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 334 milliseconds Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 335 milliseconds Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":109305,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":109305,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 12654ms Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 12655ms Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 5696ms Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 5692ms Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 5689ms Jul 18 09:52:30 volumio volumio[795]: info: sendMpdCommand playlistinfo took 322 milliseconds Jul 18 09:52:30 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:30 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:30 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:30 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119889,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Jingle - Assassination 06 -","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:30 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:30 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:30 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:30 volumio volumio[795]: info: ------------------------------ 720ms Jul 18 09:52:31 volumio volumio[795]: Upnp client error: Error: This socket has been ended by the other party Jul 18 09:52:40 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:52:40 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:52:40 volumio volumio[795]: info: Discovery: Getting this device information Jul 18 09:52:40 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Jul 18 09:52:40 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:45 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:45 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:45 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces system playlist update Jul 18 09:52:45 volumio volumio[795]: info: Ignoring MPD Status Update Jul 18 09:52:45 volumio volumio[795]: info: Jul 18 09:52:45 volumio volumio[795]: ---------------------------- MPD announces state update: player Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::getState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand status Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 12ms Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand status took 11 milliseconds Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 10ms Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand status took 8 milliseconds Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 8ms Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand status took 6 milliseconds Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 6ms Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand status took 4 milliseconds Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::parseState Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 18 09:52:45 volumio volumio[795]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:45 volumio volumio[795]: verbose: ControllerMpd::parseTrackInfo Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:45 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":135495,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nakajima Megumi - Jellyfish no Kokuhaku - [Kobato]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:45 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:45 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":135495,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nakajima Megumi - Jellyfish no Kokuhaku - [Kobato]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:45 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:45 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":135495,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nakajima Megumi - Jellyfish no Kokuhaku - [Kobato]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:45 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: ControllerMpd::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::servicePushState Jul 18 09:52:45 volumio volumio[795]: info: CorePlayQueue::getTrack 0 Jul 18 09:52:45 volumio volumio[795]: verbose: STATE SERVICE {"status":"play","position":0,"seek":135495,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nakajima Megumi - Jellyfish no Kokuhaku - [Kobato]","artist":"Tsubaki Web Radio","album":null,"uri":"http://stream.tsubakianimeradio.com:9000/play.mp3","trackType":"mp3"} Jul 18 09:52:45 volumio volumio[795]: verbose: CURRENT POSITION 0 Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState stateService play Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::syncState currentStatus play Jul 18 09:52:45 volumio volumio[795]: info: Received an update from plugin. extracting info from payload Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: CoreStateMachine::pushState Jul 18 09:52:45 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 186ms Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 184ms Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 182ms Jul 18 09:52:45 volumio volumio[795]: info: ------------------------------ 180ms Jul 18 09:52:48 volumio volumio[795]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:52:48 volumio volumio[795]: Error: connect ETIMEDOUT 128.242.240.189:80 Jul 18 09:52:48 volumio volumio[795]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 18 09:52:48 volumio volumio[795]: errno: -110, Jul 18 09:52:48 volumio volumio[795]: code: 'ETIMEDOUT', Jul 18 09:52:48 volumio volumio[795]: syscall: 'connect', Jul 18 09:52:48 volumio volumio[795]: address: '128.242.240.189', Jul 18 09:52:48 volumio volumio[795]: port: 80 Jul 18 09:52:48 volumio volumio[795]: } Jul 18 09:52:48 volumio volumio[795]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:52:49 volumio sudo[4000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 09:51 Jul 18 09:52:49 volumio sudo[4000]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"