-- Logs begin at Wed 2024-10-09 14:33:04 CST, end at Thu 2024-10-10 15:12:35 CST. -- Oct 10 15:11:43 volumio nmbd[781]: [2024/10/10 15:11:43.481482, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 10 15:11:43 volumio nmbd[781]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.10 for name WORKGROUP<1d>. Oct 10 15:11:43 volumio nmbd[781]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Oct 10 15:11:47 volumio kernel: usb 1-1.4: USB disconnect, device number 20 Oct 10 15:11:47 volumio volumio[17084]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/wwn-0x5000039b62c88d44-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.4:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/be6aa982-01 /dev/disk/by-id/ata-TOSHIBA_MQ04ABD200_326NP3QBT-part1","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","DEVTYPE":"partition","DISKSEQ":"45","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_APM":"1","ID_ATA_FEATURE_SET_APM_CURRENT_VALUE":"128","ID_ATA_FEATURE_SET_APM_ENABLED":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"65962","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"65962","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_MODEL":"TOSHIBA_MQ04ABD200","ID_MODEL_ENC":"TOSHIBA\\x20MQ04ABD200\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"63","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3907024002","ID_PART_ENTRY_TYPE":"0xf","ID_PART_ENTRY_UUID":"be6aa982-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"be6aa982","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"JT001U","ID_SERIAL":"TOSHIBA_MQ04ABD200_326NP3QBT","ID_SERIAL_SHORT":"326NP3QBT","ID_TYPE":"disk","ID_WWN":"0x5000039b62c88d44","ID_WWN_WITH_EXTENSION":"0x5000039b62c88d44","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"3025","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1390767712571"} Oct 10 15:11:47 volumio volumio[17084]: error: Cannot associate FS Label, not mounting Oct 10 15:11:47 volumio volumio[17084]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc5","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/FE5CD09E5CD0534F /dev/disk/by-id/wwn-0x5000039b62c88d44-part5 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.4:1.0-scsi-0:0:0:0-part5 /dev/disk/by-partuuid/be6aa982-05 /dev/disk/by-id/ata-TOSHIBA_MQ04ABD200_326NP3QBT-part5","DEVNAME":"/dev/sdc5","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc5","DEVTYPE":"partition","DISKSEQ":"45","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_APM":"1","ID_ATA_FEATURE_SET_APM_CURRENT_VALUE":"128","ID_ATA_FEATURE_SET_APM_ENABLED":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"65962","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"65962","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"FE5CD09E5CD0534F","ID_FS_UUID_ENC":"FE5CD09E5CD0534F","ID_MODEL":"TOSHIBA_MQ04ABD200","ID_MODEL_ENC":"TOSHIBA\\x20MQ04ABD200\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_NUMBER":"5","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3907020800","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"be6aa982-05","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"be6aa982","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"JT001U","ID_SERIAL":"TOSHIBA_MQ04ABD200_326NP3QBT","ID_SERIAL_SHORT":"326NP3QBT","ID_TYPE":"disk","ID_WWN":"0x5000039b62c88d44","ID_WWN_WITH_EXTENSION":"0x5000039b62c88d44","MAJOR":"8","MINOR":"37","PARTN":"5","SEQNUM":"3026","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1390767873295"} Oct 10 15:11:47 volumio kernel: sd 2:0:0:0: [sdc] Synchronizing SCSI cache Oct 10 15:11:48 volumio kernel: sd 2:0:0:0: [sdc] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Oct 10 15:11:56 volumio kernel: usb 2-1: new SuperSpeed USB device number 3 using xhci_hcd Oct 10 15:11:56 volumio kernel: usb 2-1: New USB device found, idVendor=8888, idProduct=7777, bcdDevice= 0.56 Oct 10 15:11:56 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 10 15:11:56 volumio kernel: usb 2-1: Product: Externa Oct 10 15:11:56 volumio kernel: usb 2-1: Manufacturer: Generic Oct 10 15:11:56 volumio kernel: usb 2-1: SerialNumber: 22222222222222220138 Oct 10 15:11:56 volumio kernel: scsi host2: uas Oct 10 15:11:56 volumio kernel: scsi 2:0:0:0: Direct-Access Generic Externa 0056 PQ: 0 ANSI: 6 Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: [sdc] 3907029167 512-byte logical blocks: (2.00 TB/1.82 TiB) Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: [sdc] Write Protect is off Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: [sdc] Mode Sense: 47 00 00 08 Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: [sdc] Preferred minimum I/O size 512 bytes Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: [sdc] Optimal transfer size 33553920 bytes Oct 10 15:11:56 volumio kernel: sdc: sdc1 < sdc5 > Oct 10 15:11:56 volumio kernel: sd 2:0:0:0: [sdc] Attached SCSI disk Oct 10 15:11:56 volumio volumio[17084]: error: Cannot associate FS Label, not mounting Oct 10 15:11:57 volumio volumio[17084]: info: Mounting Device FE5CD09E5CD0534F Oct 10 15:11:57 volumio volumio[17084]: /bin/mkdir: cannot create directory ‘/mnt/USB/FE5CD09E5CD0534F’: File exists Oct 10 15:11:57 volumio volumio[17084]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/FE5CD09E5CD0534F" Oct 10 15:11:57 volumio volumio[17084]: /bin/mkdir: cannot create directory ‘/mnt/USB/FE5CD09E5CD0534F’: File exists Oct 10 15:11:57 volumio sudo[12832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc5 /mnt/USB/FE5CD09E5CD0534F -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 15:11:57 volumio sudo[12832]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 15:11:58 volumio sudo[12832]: pam_unix(sudo:session): session closed for user root Oct 10 15:11:58 volumio volumio[17084]: ntfs-3g-mount: failed to access mountpoint /media/FE5CD09E5CD0534F: Transport endpoint is not connected Oct 10 15:11:58 volumio volumio[17084]: error: Failed to mount FE5CD09E5CD0534F: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdc5" "/mnt/USB/FE5CD09E5CD0534F" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 15:11:58 volumio volumio[17084]: ntfs-3g-mount: failed to access mountpoint /media/FE5CD09E5CD0534F: Transport endpoint is not connected Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:02 volumio volumio[17084]: info: Retrieving Cloud Streaming UI Oct 10 15:12:02 volumio volumio[17084]: info: Getting Tidal Cloud Configuration Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:02 volumio volumio[17084]: info: Getting Qobuz Cloud Configuration Oct 10 15:12:02 volumio volumio[17084]: info: Asking plugin for UI Config Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:02 volumio volumio[17084]: info: Getting Spotify Cloud Configuration Oct 10 15:12:02 volumio volumio[17084]: info: Asking plugin for UI Config Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:02 volumio volumio[17084]: info: Saving Spotify Acccount Oct 10 15:12:02 volumio volumio[17084]: info: Got Tidal Cloud Configuration Oct 10 15:12:02 volumio volumio[17084]: info: Got it Oct 10 15:12:02 volumio volumio[17084]: info: Got it Oct 10 15:12:02 volumio volumio[17084]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:02 volumio volumio[17084]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 15:12:02 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 10 15:12:03 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand update Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:03 volumio volumio[17084]: info: Oct 10 15:12:03 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand update took 1 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:03 volumio volumio[17084]: info: Oct 10 15:12:03 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:03 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:03 volumio volumio[17084]: info: Oct 10 15:12:03 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:03 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:03 volumio volumio[17084]: info: Oct 10 15:12:03 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:03 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 4 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 3 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 4 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 3 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 3 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:03 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:03 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:03 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:03 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:03 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:03 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:03 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:03 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:04 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:04 volumio volumio[17084]: info: Oct 10 15:12:04 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:04 volumio volumio[17084]: info: Oct 10 15:12:04 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand status took 3 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:04 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:04 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:04 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:04 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112685,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:04 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:04 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:04 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:04 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112685,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:04 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:04 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:04 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:04 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112685,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:04 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:04 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:04 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:04 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112685,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:04 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:04 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: ------------------------------ 175ms Oct 10 15:12:04 volumio volumio[17084]: info: ------------------------------ 175ms Oct 10 15:12:04 volumio volumio[17084]: info: ------------------------------ 174ms Oct 10 15:12:04 volumio volumio[17084]: info: ------------------------------ 173ms Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 10 15:12:04 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:04 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:04 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:04 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112777,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:04 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:04 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:04 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:04 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112777,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:04 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:04 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:04 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:04 volumio volumio[17084]: info: ------------------------------ 93ms Oct 10 15:12:04 volumio volumio[17084]: info: ------------------------------ 93ms Oct 10 15:12:06 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand rescan Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: Oct 10 15:12:10 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand rescan took 2 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: Oct 10 15:12:10 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: Oct 10 15:12:10 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: Oct 10 15:12:10 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 45 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 46 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 3 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:10 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:10 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119279,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:10 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:10 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: ------------------------------ 129ms Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: Oct 10 15:12:10 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: Oct 10 15:12:10 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 62 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 6 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 5 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 5 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand status took 4 milliseconds Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:10 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:10 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119279,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:10 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:10 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:10 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:10 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119279,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:10 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:10 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:10 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:10 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119279,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:10 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:10 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: ------------------------------ 199ms Oct 10 15:12:10 volumio volumio[17084]: info: ------------------------------ 157ms Oct 10 15:12:10 volumio volumio[17084]: info: ------------------------------ 156ms Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 10 15:12:10 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:10 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:10 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:10 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119382,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:10 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:10 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:10 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:10 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":119382,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:10 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:10 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:10 volumio volumio[17084]: info: ------------------------------ 81ms Oct 10 15:12:10 volumio volumio[17084]: info: ------------------------------ 80ms Oct 10 15:12:10 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 15:12:14 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 15:12:18 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 15:12:18 volumio volumio[17084]: info: CURURI: music-library Oct 10 15:12:18 volumio volumio[17084]: info: Preload queue cleared Oct 10 15:12:19 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 15:12:19 volumio volumio[17084]: info: CURURI: music-library/USB Oct 10 15:12:19 volumio volumio[17084]: error: Failed LSINFO: null Oct 10 15:12:19 volumio volumio[17084]: info: Preload queue cleared Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand update Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: Oct 10 15:12:24 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand update took 2 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: Oct 10 15:12:24 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: Oct 10 15:12:24 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 3 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: Oct 10 15:12:24 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 30 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 29 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 29 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 28 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 3 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:24 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:24 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132879,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:24 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:24 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: ------------------------------ 112ms Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: Oct 10 15:12:24 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: Oct 10 15:12:24 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 5 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 5 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 4 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand status took 4 milliseconds Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:24 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:24 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132879,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:24 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:24 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:24 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:24 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132879,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:24 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:24 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:24 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:24 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132879,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:24 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:24 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: ------------------------------ 178ms Oct 10 15:12:24 volumio volumio[17084]: info: ------------------------------ 178ms Oct 10 15:12:24 volumio volumio[17084]: info: ------------------------------ 151ms Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 10 15:12:24 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:24 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:24 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:24 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132982,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:24 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:24 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:24 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:24 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132982,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:24 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:24 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:24 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:24 volumio volumio[17084]: info: ------------------------------ 85ms Oct 10 15:12:24 volumio volumio[17084]: info: ------------------------------ 84ms Oct 10 15:12:26 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 15:12:26 volumio volumio[17084]: info: CURURI: music-library Oct 10 15:12:26 volumio volumio[17084]: info: Preload queue cleared Oct 10 15:12:27 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 10 15:12:27 volumio volumio[17084]: info: CURURI: music-library/USB Oct 10 15:12:27 volumio volumio[17084]: error: Failed LSINFO: null Oct 10 15:12:27 volumio volumio[17084]: info: Preload queue cleared Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:32 volumio volumio[17084]: info: Retrieving Cloud Streaming UI Oct 10 15:12:32 volumio volumio[17084]: info: Getting Tidal Cloud Configuration Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:32 volumio volumio[17084]: info: Getting Qobuz Cloud Configuration Oct 10 15:12:32 volumio volumio[17084]: info: Asking plugin for UI Config Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:32 volumio volumio[17084]: info: Getting Spotify Cloud Configuration Oct 10 15:12:32 volumio volumio[17084]: info: Asking plugin for UI Config Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:32 volumio volumio[17084]: info: Saving Spotify Acccount Oct 10 15:12:32 volumio volumio[17084]: info: Got Tidal Cloud Configuration Oct 10 15:12:32 volumio volumio[17084]: info: Got it Oct 10 15:12:32 volumio volumio[17084]: info: Got it Oct 10 15:12:32 volumio volumio[17084]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 15:12:32 volumio volumio[17084]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 15:12:32 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand rescan Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: Oct 10 15:12:34 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand rescan took 1 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: Oct 10 15:12:34 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 1 milliseconds Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: Oct 10 15:12:34 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: Oct 10 15:12:34 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 40 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 39 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 2 milliseconds Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:34 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:34 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":142982,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:34 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:34 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: ------------------------------ 122ms Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: Oct 10 15:12:34 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: Oct 10 15:12:34 volumio volumio[17084]: ---------------------------- MPD announces state update: update Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::getState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand status Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 5 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 5 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 4 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand status took 4 milliseconds Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: info: Command Router : Notfying DB Updatefalse Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::Close All Modals sent Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseState Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:34 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:34 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":142982,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:34 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:34 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:34 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:34 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143085,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:34 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:34 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:34 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:34 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143085,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:34 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:34 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: ------------------------------ 192ms Oct 10 15:12:34 volumio volumio[17084]: info: ------------------------------ 154ms Oct 10 15:12:34 volumio volumio[17084]: info: ------------------------------ 154ms Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 10 15:12:34 volumio volumio[17084]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:34 volumio volumio[17084]: verbose: ControllerMpd::parseTrackInfo Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:34 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:34 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143177,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:34 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:34 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: ControllerMpd::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::servicePushState Oct 10 15:12:34 volumio volumio[17084]: info: CorePlayQueue::getTrack 0 Oct 10 15:12:34 volumio volumio[17084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143177,"duration":163,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4207.wav","artist":null,"album":null,"uri":"http://192.168.1.4:8202/MediaItems/4207.wav","trackType":"wav"} Oct 10 15:12:34 volumio volumio[17084]: verbose: CURRENT POSITION 0 Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState stateService play Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::syncState currentStatus play Oct 10 15:12:34 volumio volumio[17084]: info: Received an update from plugin. extracting info from payload Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreStateMachine::pushState Oct 10 15:12:34 volumio volumio[17084]: info: CoreCommandRouter::volumioPushState Oct 10 15:12:34 volumio volumio[17084]: info: ------------------------------ 79ms Oct 10 15:12:34 volumio volumio[17084]: info: ------------------------------ 78ms Oct 10 15:12:35 volumio volumio[17084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 15:12:35 volumio volumio[17084]: Error: connect ETIMEDOUT 31.13.87.19:80 Oct 10 15:12:35 volumio volumio[17084]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 10 15:12:35 volumio volumio[17084]: errno: -110, Oct 10 15:12:35 volumio volumio[17084]: code: 'ETIMEDOUT', Oct 10 15:12:35 volumio volumio[17084]: syscall: 'connect', Oct 10 15:12:35 volumio volumio[17084]: address: '31.13.87.19', Oct 10 15:12:35 volumio volumio[17084]: port: 80 Oct 10 15:12:35 volumio volumio[17084]: } Oct 10 15:12:35 volumio volumio[17084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 15:12:35 volumio sudo[12937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 15:11 Oct 10 15:12:35 volumio sudo[12937]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"