-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Mon 2024-12-02 10:19:48 MSK. -- Dec 02 10:18:00 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:01 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:02 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:02 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:03 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:04 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:05 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:06 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:06 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:07 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:08 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:09 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:10 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:10 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:11 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:12 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:13 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:14 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:14 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:15 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:16 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:17 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:18 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:18 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:19 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:20 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:21 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:22 volumio kernel: usb 4-1: USB disconnect, device number 16 Dec 02 10:18:22 volumio volumio[1083]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb4/4-1/4-1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-USB3.0_high_speed_000000123AE7-0:0-part1 /dev/disk/by-path/platform-xhci-hcd.1-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/60D3-06D9 /dev/disk/by-partuuid/d9dda18c-01 /dev/disk/by-label/Новый\\x20том","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb4/4-1/4-1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"30","ID_BUS":"usb","ID_FS_LABEL":"Новый_том","ID_FS_LABEL_ENC":"Новый\\x20том","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"60D3-06D9","ID_FS_UUID_ENC":"60D3-06D9","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"high_speed","ID_MODEL_ENC":"high\\x20speed\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0711","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"468856832","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"d9dda18c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"d9dda18c","ID_PATH":"platform-xhci-hcd.1-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_1-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"2210","ID_SERIAL":"USB3.0_high_speed_000000123AE7-0:0","ID_SERIAL_SHORT":"000000123AE7","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"USB3.0","ID_VENDOR_ENC":"USB3.0\\x20\\x20","ID_VENDOR_ID":"2109","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"3180","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"986931051"} Dec 02 10:18:22 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:22 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Dec 02 10:18:22 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK Dec 02 10:18:22 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:23 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:24 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:25 volumio kernel: usb 3-2: new high-speed USB device number 2 using xhci-hcd Dec 02 10:18:25 volumio kernel: usb 3-2: New USB device found, idVendor=2109, idProduct=0711, bcdDevice= 1.44 Dec 02 10:18:25 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 02 10:18:25 volumio kernel: usb 3-2: Product: VLI Product String Dec 02 10:18:25 volumio kernel: usb 3-2: Manufacturer: VLI manufacture String Dec 02 10:18:25 volumio kernel: usb 3-2: SerialNumber: 000000123AE7 Dec 02 10:18:25 volumio kernel: usb-storage 3-2:1.0: USB Mass Storage device detected Dec 02 10:18:25 volumio kernel: usb-storage 3-2:1.0: Quirks match for vid 2109 pid 0711: 2000000 Dec 02 10:18:25 volumio kernel: scsi host1: usb-storage 3-2:1.0 Dec 02 10:18:25 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:26 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:26 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:27 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:28 volumio kernel: scsi 1:0:0:0: Direct-Access USB3.0 high speed 2210 PQ: 0 ANSI: 6 Dec 02 10:18:28 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 02 10:18:28 volumio kernel: sd 1:0:0:0: [sdb] 468862124 512-byte logical blocks: (240 GB/224 GiB) Dec 02 10:18:28 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Dec 02 10:18:28 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 2f 00 00 00 Dec 02 10:18:28 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 02 10:18:28 volumio kernel: sdb: sdb1 Dec 02 10:18:28 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Dec 02 10:18:28 volumio volumio[1083]: info: Mounting Device Новый_том Dec 02 10:18:28 volumio volumio[1083]: /bin/mkdir: cannot create directory ‘/mnt/USB/Новый_том’: File exists Dec 02 10:18:28 volumio volumio[1083]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Новый_том" Dec 02 10:18:28 volumio volumio[1083]: /bin/mkdir: cannot create directory ‘/mnt/USB/Новый_том’: File exists Dec 02 10:18:28 volumio sudo[5128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/Новый_том -o noatime Dec 02 10:18:28 volumio sudo[5128]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 10:18:28 volumio mount.exfat[5130]: volume was not unmounted cleanly Dec 02 10:18:28 volumio sudo[5128]: pam_unix(sudo:session): session closed for user root Dec 02 10:18:28 volumio volumio[1083]: WARN: volume was not unmounted cleanly. Dec 02 10:18:28 volumio volumio[1083]: fuse: bad mount point `/mnt/USB/Новый_том': Transport endpoint is not connected Dec 02 10:18:28 volumio volumio[1083]: error: Failed to mount Новый_том: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/Новый_том" -o noatime Dec 02 10:18:28 volumio volumio[1083]: WARN: volume was not unmounted cleanly. Dec 02 10:18:28 volumio volumio[1083]: fuse: bad mount point `/mnt/USB/Новый_том': Transport endpoint is not connected Dec 02 10:18:28 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:29 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:30 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:30 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand update Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:31 volumio volumio[1083]: info: Dec 02 10:18:31 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:18:31 volumio volumio[1083]: info: sendMpdCommand update took 0 milliseconds Dec 02 10:18:31 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:31 volumio volumio[1083]: info: Dec 02 10:18:31 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:18:31 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:18:31 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:31 volumio volumio[1083]: info: Command Router : Notfying DB Updatetrue Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:18:31 volumio volumio[1083]: info: sendMpdCommand status took 41 milliseconds Dec 02 10:18:31 volumio volumio[1083]: info: sendMpdCommand status took 40 milliseconds Dec 02 10:18:31 volumio volumio[1083]: info: sendMpdCommand status took 40 milliseconds Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:31 volumio volumio[1083]: info: Command Router : Notfying DB Updatefalse Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:18:31 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:31 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 02 10:18:31 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:31 volumio volumio[1083]: info: No code Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:31 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 02 10:18:31 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:31 volumio volumio[1083]: info: No code Dec 02 10:18:31 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:31 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:31 volumio volumio[1083]: info: ------------------------------ 57ms Dec 02 10:18:31 volumio volumio[1083]: info: ------------------------------ 57ms Dec 02 10:18:31 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:32 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:33 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:34 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:34 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:35 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand rescan Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:37 volumio volumio[1083]: info: Dec 02 10:18:37 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:18:37 volumio volumio[1083]: info: sendMpdCommand rescan took 0 milliseconds Dec 02 10:18:37 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:37 volumio volumio[1083]: info: Dec 02 10:18:37 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:18:37 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:18:37 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:37 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:37 volumio volumio[1083]: info: Command Router : Notfying DB Updatefalse Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:37 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 02 10:18:37 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:37 volumio volumio[1083]: info: No code Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:37 volumio volumio[1083]: info: ------------------------------ 14ms Dec 02 10:18:37 volumio volumio[1083]: info: sendMpdCommand status took 14 milliseconds Dec 02 10:18:37 volumio volumio[1083]: info: sendMpdCommand status took 14 milliseconds Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:37 volumio volumio[1083]: info: Command Router : Notfying DB Updatefalse Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:18:37 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:37 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 02 10:18:37 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:37 volumio volumio[1083]: info: No code Dec 02 10:18:37 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:37 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:37 volumio volumio[1083]: info: ------------------------------ 27ms Dec 02 10:18:37 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:38 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:38 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:39 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:40 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:41 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:42 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:42 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:43 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:44 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand update Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:45 volumio volumio[1083]: info: Dec 02 10:18:45 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:18:45 volumio volumio[1083]: info: sendMpdCommand update took 1 milliseconds Dec 02 10:18:45 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:45 volumio volumio[1083]: info: Dec 02 10:18:45 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:18:45 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:18:45 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:18:45 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:45 volumio volumio[1083]: info: Command Router : Notfying DB Updatetrue Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:45 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 02 10:18:45 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:45 volumio volumio[1083]: info: No code Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:45 volumio volumio[1083]: info: ------------------------------ 25ms Dec 02 10:18:45 volumio volumio[1083]: info: sendMpdCommand status took 25 milliseconds Dec 02 10:18:45 volumio volumio[1083]: info: sendMpdCommand status took 24 milliseconds Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:45 volumio volumio[1083]: info: Command Router : Notfying DB Updatefalse Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:18:45 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:18:45 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 02 10:18:45 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::syncState stateService stop Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:45 volumio volumio[1083]: info: No code Dec 02 10:18:45 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:45 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:45 volumio volumio[1083]: info: ------------------------------ 39ms Dec 02 10:18:45 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:46 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:46 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:47 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:48 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:49 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:50 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:18:50 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:51 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:52 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:53 volumio volumio[1083]: info: CoreCommandRouter::volumioGetState Dec 02 10:18:53 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:53 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:54 volumio volumio[1083]: info: CoreCommandRouter::volumioPlay Dec 02 10:18:54 volumio volumio[1083]: info: CoreStateMachine::play index undefined Dec 02 10:18:54 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 10:18:54 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:54 volumio volumio[1083]: info: CoreStateMachine::startPlaybackTimer Dec 02 10:18:54 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:54 volumio volumio[1083]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Prince And The Revolution - Purple Rain_DSD_Stig/A1.Let's Go Crazy.dsf Dec 02 10:18:54 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 10:18:54 volumio volumio[1083]: info: sendMpdCommand stop took 1 milliseconds Dec 02 10:18:54 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 10:18:54 volumio volumio[1083]: info: Dec 02 10:18:54 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:18:54 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:18:54 volumio volumio[1083]: info: sendMpdCommand clear took 0 milliseconds Dec 02 10:18:54 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Prince And The Revolution - Purple Rain_DSD_Stig/A1.Let's Go Crazy.dsf" Dec 02 10:18:54 volumio volumio[1083]: error: updateQueue error: null Dec 02 10:18:54 volumio volumio[1083]: info: ------------------------------ 0ms Dec 02 10:18:54 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:55 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:56 volumio volumio[1083]: info: CoreCommandRouter::volumioSeek Dec 02 10:18:56 volumio volumio[1083]: info: CoreStateMachine::seek Dec 02 10:18:56 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:56 volumio volumio[1083]: info: TRACKBLOCK {"uri":"music-library/USB/Новый_том/Prince And The Revolution - Purple Rain_DSD_Stig/A1.Let's Go Crazy.dsf","service":"mpd","name":"A1.Let's Go Crazy","artist":"Prince And The Revolution","album":"Purple Rain","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=928&web=Prince%20And%20The%20Revolution/Purple%20Rain/extralarge&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2FPrince%20And%20The%20Revolution%20-%20Purple%20Rain_DSD_Stig&metadata=false","duration":280,"trackType":"dsf"} Dec 02 10:18:56 volumio volumio[1083]: info: CoreStateMachine::startPlaybackTimer Dec 02 10:18:56 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:56 volumio volumio[1083]: info: ControllerMpd::seek Dec 02 10:18:56 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:18:56 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:18:56 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:18:56 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:18:56 volumio volumio[1083]: error: [2@0] {seek} Bad song index Dec 02 10:18:56 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:57 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:58 volumio volumio[1083]: info: CoreCommandRouter::volumioNext Dec 02 10:18:58 volumio volumio[1083]: info: CoreStateMachine::next Dec 02 10:18:58 volumio volumio[1083]: info: CoreStateMachine::stop Dec 02 10:18:58 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 10:18:58 volumio volumio[1083]: info: CoreStateMachine::play index undefined Dec 02 10:18:58 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 10:18:58 volumio volumio[1083]: info: CorePlayQueue::getTrack 1 Dec 02 10:18:58 volumio volumio[1083]: info: CoreStateMachine::startPlaybackTimer Dec 02 10:18:58 volumio volumio[1083]: info: CorePlayQueue::getTrack 1 Dec 02 10:18:58 volumio volumio[1083]: verbose: ControllerMpd::clearAddPlayTracks USB/Новый_том/Prince And The Revolution - Purple Rain_DSD_Stig/A2.Take Me With U.dsf Dec 02 10:18:58 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 10:18:58 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock Dec 02 10:18:58 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock Dec 02 10:18:58 volumio volumio[1083]: info: sendMpdCommand stop took 1 milliseconds Dec 02 10:18:58 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 10:18:58 volumio volumio[1083]: info: Dec 02 10:18:58 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:18:58 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:18:58 volumio volumio[1083]: info: sendMpdCommand clear took 0 milliseconds Dec 02 10:18:58 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand add "USB/Новый_том/Prince And The Revolution - Purple Rain_DSD_Stig/A2.Take Me With U.dsf" Dec 02 10:18:58 volumio volumio[1083]: error: updateQueue error: null Dec 02 10:18:58 volumio volumio[1083]: info: ------------------------------ 0ms Dec 02 10:18:58 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:18:59 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:00 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:01 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:02 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:03 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:04 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:05 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:06 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:07 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 02 10:19:07 volumio volumio[1083]: info: CURURI: albums:// Dec 02 10:19:07 volumio volumio[1083]: info: listAlbums - loading Albums from cache Dec 02 10:19:07 volumio volumio[1083]: info: Preload queue cleared Dec 02 10:19:07 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:08 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:09 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:10 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:10 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 02 10:19:11 volumio volumio[1083]: info: Preload queue cleared Dec 02 10:19:11 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:12 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:13 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 02 10:19:13 volumio volumio[1083]: info: Preload queue cleared Dec 02 10:19:14 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:14 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:15 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:16 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:17 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:18 volumio volumio[1083]: info: Preload queue cleared Dec 02 10:19:18 volumio volumio[1083]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::ClearQueue Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::stop Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 10:19:18 volumio volumio[1083]: info: CorePlayQueue::clearPlayQueue Dec 02 10:19:18 volumio volumio[1083]: info: CorePlayQueue::saveQueue Dec 02 10:19:18 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::addQueueItems Dec 02 10:19:18 volumio volumio[1083]: info: CorePlayQueue::addQueueItems Dec 02 10:19:18 volumio volumio[1083]: info: Preload queue cleared Dec 02 10:19:18 volumio volumio[1083]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497966 Dec 02 10:19:18 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 02 10:19:18 volumio volumio[1083]: info: CoreCommandRouter::volumioPushQueue Dec 02 10:19:18 volumio volumio[1083]: info: CorePlayQueue::saveQueue Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::updateTrackBlock Dec 02 10:19:18 volumio volumio[1083]: info: CorePlayQueue::getTrackBlock Dec 02 10:19:18 volumio volumio[1083]: info: CoreCommandRouter::volumioPlay Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::play index 0 Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::stop Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::play index undefined Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 10:19:18 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:18 volumio volumio[1083]: info: CoreStateMachine::startPlaybackTimer Dec 02 10:19:18 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:18 volumio volumio[1083]: info: [1733123958415] ControllerWebradio::clearAddPlayTrack Dec 02 10:19:18 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 10:19:18 volumio volumio[1083]: info: sendMpdCommand stop took 0 milliseconds Dec 02 10:19:18 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 10:19:18 volumio volumio[1083]: info: Dec 02 10:19:18 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:19:18 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:19:18 volumio volumio[1083]: info: sendMpdCommand clear took 1 milliseconds Dec 02 10:19:18 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497966" Dec 02 10:19:18 volumio volumio[1083]: error: updateQueue error: null Dec 02 10:19:18 volumio volumio[1083]: info: ------------------------------ 0ms Dec 02 10:19:18 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:19 volumio volumio[1083]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497966" took 836 milliseconds Dec 02 10:19:19 volumio volumio[1083]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 10:19:19 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand play Dec 02 10:19:19 volumio volumio[1083]: info: Dec 02 10:19:19 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:19:19 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:19:19 volumio volumio[1083]: info: sendMpdCommand play took 0 milliseconds Dec 02 10:19:19 volumio volumio[1083]: info: ------------------------------ 0ms Dec 02 10:19:19 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:20 volumio volumio[1083]: info: Dec 02 10:19:20 volumio volumio[1083]: ---------------------------- MPD announces state update: player Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:20 volumio volumio[1083]: info: Dec 02 10:19:20 volumio volumio[1083]: ---------------------------- MPD announces state update: player Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand status took 0 milliseconds Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:20 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:20 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"heavy-metal","artist":"(#1 - 324/500000) ROCK ANTENNE Heavy Metal (Germany)","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:20 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus stop Dec 02 10:19:20 volumio volumio[1083]: info: ------------------------------ 3ms Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:20 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:20 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"heavy-metal","artist":"(#1 - 324/500000) ROCK ANTENNE Heavy Metal (Germany)","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:20 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:20 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:20 volumio volumio[1083]: info: ------------------------------ 6ms Dec 02 10:19:20 volumio volumio[1083]: info: Dec 02 10:19:20 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:19:20 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:19:20 volumio volumio[1083]: info: Dec 02 10:19:20 volumio volumio[1083]: ---------------------------- MPD announces state update: player Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:20 volumio volumio[1083]: info: Dec 02 10:19:20 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:19:20 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:19:20 volumio volumio[1083]: info: Dec 02 10:19:20 volumio volumio[1083]: ---------------------------- MPD announces state update: player Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:20 volumio volumio[1083]: info: ------------------------------ 1ms Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:19:20 volumio volumio[1083]: info: ------------------------------ 1ms Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand status took 0 milliseconds Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 10:19:20 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:20 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:20 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:20 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Doro - All For Metal","artist":"ROCK ANTENNE Heavy Metal","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:20 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:20 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:20 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:20 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:20 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Doro - All For Metal","artist":"ROCK ANTENNE Heavy Metal","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:20 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:20 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:20 volumio volumio[1083]: info: ------------------------------ 6ms Dec 02 10:19:20 volumio volumio[1083]: info: ------------------------------ 6ms Dec 02 10:19:20 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:21 volumio volumio[1083]: info: Dec 02 10:19:21 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:19:21 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:19:21 volumio volumio[1083]: info: Dec 02 10:19:21 volumio volumio[1083]: ---------------------------- MPD announces state update: player Dec 02 10:19:21 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:21 volumio volumio[1083]: info: ------------------------------ 2ms Dec 02 10:19:21 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:19:21 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:21 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:21 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 10:19:21 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:21 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:21 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:21 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:21 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Doro - All For Metal","artist":"ROCK ANTENNE Heavy Metal","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:21 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:21 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:21 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:21 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:21 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:21 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:21 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:19:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:21 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:21 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:21 volumio volumio[1083]: info: ------------------------------ 6ms Dec 02 10:19:21 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:22 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:23 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:24 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:25 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:26 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:27 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:28 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:29 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 02 10:19:29 volumio volumio[1083]: info: CURURI: albums:// Dec 02 10:19:29 volumio volumio[1083]: info: listAlbums - loading Albums from cache Dec 02 10:19:29 volumio volumio[1083]: info: Preload queue cleared Dec 02 10:19:29 volumio volumio[1083]: info: Dec 02 10:19:29 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:19:29 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:19:29 volumio volumio[1083]: info: Dec 02 10:19:29 volumio volumio[1083]: ---------------------------- MPD announces state update: player Dec 02 10:19:29 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:29 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:29 volumio volumio[1083]: info: ------------------------------ 1ms Dec 02 10:19:29 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:19:29 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:29 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:29 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 02 10:19:29 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:29 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:29 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:29 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:29 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Heaven Shall Burn - The weapon they fear","artist":"ROCK ANTENNE Heavy Metal","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:29 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:29 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:29 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:29 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:29 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:29 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:29 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:19:29 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:29 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:29 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:29 volumio volumio[1083]: info: ------------------------------ 4ms Dec 02 10:19:29 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:30 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:31 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:32 volumio volumio[1083]: info: Dec 02 10:19:32 volumio volumio[1083]: ---------------------------- MPD announces system playlist update Dec 02 10:19:32 volumio volumio[1083]: info: Ignoring MPD Status Update Dec 02 10:19:32 volumio volumio[1083]: info: Dec 02 10:19:32 volumio volumio[1083]: ---------------------------- MPD announces state update: player Dec 02 10:19:32 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:32 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:32 volumio volumio[1083]: info: ------------------------------ 1ms Dec 02 10:19:32 volumio volumio[1083]: info: sendMpdCommand status took 0 milliseconds Dec 02 10:19:32 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:32 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:32 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 10:19:32 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:32 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:32 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:32 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:32 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12677,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Heaven Shall Burn - The weapon they fear","artist":"ROCK ANTENNE Heavy Metal","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:32 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:32 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:32 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:32 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:32 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:32 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:32 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:19:32 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:32 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:32 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:32 volumio volumio[1083]: info: ------------------------------ 3ms Dec 02 10:19:32 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:33 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:34 volumio volumio[1083]: info: CoreCommandRouter::volumioGetState Dec 02 10:19:34 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:35 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 10:19:36 volumio volumio[1083]: info: Retrieving Cloud Streaming UI Dec 02 10:19:36 volumio volumio[1083]: info: Getting Tidal Cloud Configuration Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 10:19:36 volumio volumio[1083]: info: Getting Qobuz Cloud Configuration Dec 02 10:19:36 volumio volumio[1083]: info: Asking plugin for UI Config Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 10:19:36 volumio volumio[1083]: info: Getting Spotify Cloud Configuration Dec 02 10:19:36 volumio volumio[1083]: info: Asking plugin for UI Config Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 10:19:36 volumio volumio[1083]: info: Saving Spotify Acccount Dec 02 10:19:36 volumio volumio[1083]: info: Got Tidal Cloud Configuration Dec 02 10:19:36 volumio volumio[1083]: info: Got it Dec 02 10:19:36 volumio volumio[1083]: info: Got it Dec 02 10:19:36 volumio volumio[1083]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 10:19:36 volumio volumio[1083]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 02 10:19:36 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:37 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand update Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:38 volumio volumio[1083]: info: Dec 02 10:19:38 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:19:38 volumio volumio[1083]: info: sendMpdCommand update took 0 milliseconds Dec 02 10:19:38 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:38 volumio volumio[1083]: info: Dec 02 10:19:38 volumio volumio[1083]: ---------------------------- MPD announces state update: update Dec 02 10:19:38 volumio volumio[1083]: info: sendMpdCommand status took 1 milliseconds Dec 02 10:19:38 volumio volumio[1083]: info: ControllerMpd::getState Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand status Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:38 volumio volumio[1083]: info: Command Router : Notfying DB Updatefalse Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:19:38 volumio volumio[1083]: info: sendMpdCommand status took 27 milliseconds Dec 02 10:19:38 volumio volumio[1083]: info: sendMpdCommand status took 27 milliseconds Dec 02 10:19:38 volumio volumio[1083]: info: sendMpdCommand status took 27 milliseconds Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:38 volumio volumio[1083]: info: Command Router : Notfying DB Updatefalse Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::Close All Modals sent Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::parseState Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 10:19:38 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 02 10:19:38 volumio volumio[1083]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:38 volumio volumio[1083]: verbose: ControllerMpd::parseTrackInfo Dec 02 10:19:38 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:38 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:38 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Heaven Shall Burn - The weapon they fear","artist":"ROCK ANTENNE Heavy Metal","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:38 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:38 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:38 volumio volumio[1083]: info: ControllerMpd::pushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::servicePushState Dec 02 10:19:38 volumio volumio[1083]: info: CorePlayQueue::getTrack 0 Dec 02 10:19:38 volumio volumio[1083]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Heaven Shall Burn - The weapon they fear","artist":"ROCK ANTENNE Heavy Metal","album":null,"uri":"http://stream.antenne.de:80/heavy-metal","trackType":""} Dec 02 10:19:38 volumio volumio[1083]: verbose: CURRENT POSITION 0 Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::syncState stateService play Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::syncState currentStatus play Dec 02 10:19:38 volumio volumio[1083]: info: Received an update from plugin. extracting info from payload Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreStateMachine::pushState Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::volumioPushState Dec 02 10:19:38 volumio volumio[1083]: info: ------------------------------ 46ms Dec 02 10:19:38 volumio volumio[1083]: info: ------------------------------ 46ms Dec 02 10:19:38 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:39 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:40 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:19:40 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:41 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:42 volumio dnsmasq-dhcp[1074]: no address range available for DHCP request via wlan0 Dec 02 10:19:42 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:43 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:44 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 10:19:44 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:45 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:46 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 02 10:19:47 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 02 10:19:47 volumio volumio[1083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 02 10:19:47 volumio volumio[1083]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 10:19:47 volumio volumio[1083]: Error: getaddrinfo -3007 Dec 02 10:19:47 volumio volumio[1083]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Dec 02 10:19:47 volumio volumio[1083]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Dec 02 10:19:47 volumio volumio[1083]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Dec 02 10:19:47 volumio volumio[1083]: code: -3007, Dec 02 10:19:47 volumio volumio[1083]: errno: -3007, Dec 02 10:19:47 volumio volumio[1083]: syscall: 'getaddrinfo' Dec 02 10:19:47 volumio volumio[1083]: } Dec 02 10:19:47 volumio volumio[1083]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 10:19:48 volumio sudo[5346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 10:18 Dec 02 10:19:48 volumio sudo[5346]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"