Aug 29 16:12:00 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 67 Aug 29 16:12:00 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 68 using xhci-hcd Aug 29 16:12:00 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:00 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:00 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:00 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:00 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:00 volumio-pi5 kernel: hwmon hwmon3: Voltage normalised Aug 29 16:12:01 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 68 Aug 29 16:12:01 volumio-pi5 kernel: usb 1-2.2: new full-speed USB device number 69 using xhci-hcd Aug 29 16:12:01 volumio-pi5 kernel: usb 1-2-port2: attempt power cycle Aug 29 16:12:01 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 73 using xhci-hcd Aug 29 16:12:01 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:01 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:01 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:01 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:01 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:02 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 73 Aug 29 16:12:02 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 74 using xhci-hcd Aug 29 16:12:02 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:02 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:02 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:02 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:02 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:03 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 74 Aug 29 16:12:03 volumio-pi5 kernel: usb 1-2.2: new full-speed USB device number 75 using xhci-hcd Aug 29 16:12:03 volumio-pi5 kernel: usb 1-2-port2: attempt power cycle Aug 29 16:12:03 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 79 using xhci-hcd Aug 29 16:12:03 volumio-pi5 kernel: hwmon hwmon3: Undervoltage detected! Aug 29 16:12:03 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:03 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:03 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:03 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:03 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:04 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 79 Aug 29 16:12:05 volumio-pi5 kernel: usb usb1-port2: Cannot enable. Maybe the USB cable is bad? Aug 29 16:12:05 volumio-pi5 kernel: usb 1-2: new full-speed USB device number 81 using xhci-hcd Aug 29 16:12:06 volumio-pi5 kernel: usb usb1-port2: attempt power cycle Aug 29 16:12:07 volumio-pi5 kernel: hwmon hwmon3: Voltage normalised Aug 29 16:12:07 volumio-pi5 kernel: usb 1-2: new full-speed USB device number 82 using xhci-hcd Aug 29 16:12:07 volumio-pi5 kernel: usb 1-2: not running at top speed; connect to a high speed hub Aug 29 16:12:07 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:07 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:07 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:08 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:08 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:08 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 82 Aug 29 16:12:09 volumio-pi5 kernel: hwmon hwmon3: Undervoltage detected! Aug 29 16:12:09 volumio-pi5 kernel: usb usb1-port2: Cannot enable. Maybe the USB cable is bad? Aug 29 16:12:09 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 84 using xhci-hcd Aug 29 16:12:09 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:09 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:09 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:09 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:09 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:10 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 84 Aug 29 16:12:10 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 85 using xhci-hcd Aug 29 16:12:10 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:10 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:10 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:10 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:10 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:11 volumio-pi5 kernel: usb 1-2.2: new full-speed USB device number 86 using xhci-hcd Aug 29 16:12:11 volumio-pi5 kernel: usb 1-2.2: New USB device found, idVendor=222a, idProduct=0001, bcdDevice= 0.02 Aug 29 16:12:11 volumio-pi5 kernel: usb 1-2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 29 16:12:11 volumio-pi5 kernel: usb 1-2.2: Product: ILITEK-TP Aug 29 16:12:11 volumio-pi5 kernel: usb 1-2.2: Manufacturer: ILITEK Aug 29 16:12:11 volumio-pi5 kernel: input: ILITEK ILITEK-TP Touchscreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0001/input/input5 Aug 29 16:12:11 volumio-pi5 kernel: input: ILITEK ILITEK-TP Mouse as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0001/input/input7 Aug 29 16:12:11 volumio-pi5 kernel: hid-generic 0003:222A:0001.0001: input,hiddev96,hidraw0: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-xhci-hcd.0-2.2/input0 Aug 29 16:12:11 volumio-pi5 kernel: input: ILITEK ILITEK-TP as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0001/input/input8 Aug 29 16:12:11 volumio-pi5 kernel: input: ILITEK ILITEK-TP Mouse as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0001/input/input10 Aug 29 16:12:11 volumio-pi5 kernel: hid-multitouch 0003:222A:0001.0001: input,hiddev96,hidraw0: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-xhci-hcd.0-2.2/input0 Aug 29 16:12:11 volumio-pi5 kernel: usb 3-2: reset high-speed USB device number 5 using xhci-hcd Aug 29 16:12:11 volumio-pi5 kernel: sd 2:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x03 driverbyte=DRIVER_OK cmd_age=31s Aug 29 16:12:11 volumio-pi5 kernel: sd 2:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 09 86 e7 e0 00 00 10 00 Aug 29 16:12:11 volumio-pi5 kernel: I/O error, dev sdb, sector 159836128 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 2 Aug 29 16:12:11 volumio-pi5 kernel: hwmon hwmon3: Voltage normalised Aug 29 16:12:14 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 85 Aug 29 16:12:14 volumio-pi5 kernel: usb 1-2.2: USB disconnect, device number 86 Aug 29 16:12:14 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 87 using xhci-hcd Aug 29 16:12:14 volumio-pi5 kernel: usb 1-2: Device not responding to setup address. Aug 29 16:12:15 volumio-pi5 kernel: usb 1-2: Device not responding to setup address. Aug 29 16:12:15 volumio-pi5 kernel: usb 1-2: device not accepting address 87, error -71 Aug 29 16:12:15 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 88 using xhci-hcd Aug 29 16:12:15 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:15 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:15 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:15 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:15 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:16 volumio-pi5 kernel: usb 1-2.2: new full-speed USB device number 89 using xhci-hcd Aug 29 16:12:16 volumio-pi5 kernel: usb 1-2.2: New USB device found, idVendor=222a, idProduct=0001, bcdDevice= 0.02 Aug 29 16:12:16 volumio-pi5 kernel: usb 1-2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 29 16:12:16 volumio-pi5 kernel: usb 1-2.2: Product: ILITEK-TP Aug 29 16:12:16 volumio-pi5 kernel: usb 1-2.2: Manufacturer: ILITEK Aug 29 16:12:16 volumio-pi5 kernel: input: ILITEK ILITEK-TP as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0002/input/input11 Aug 29 16:12:16 volumio-pi5 kernel: input: ILITEK ILITEK-TP Mouse as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0002/input/input13 Aug 29 16:12:16 volumio-pi5 kernel: hid-multitouch 0003:222A:0001.0002: input,hiddev96,hidraw0: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-xhci-hcd.0-2.2/input0 Aug 29 16:12:16 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 88 Aug 29 16:12:16 volumio-pi5 kernel: usb 1-2.2: USB disconnect, device number 89 Aug 29 16:12:16 volumio-pi5 startx[2566]: [dix] couldn't enable device 12 Aug 29 16:12:17 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 90 using xhci-hcd Aug 29 16:12:17 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:17 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:17 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:17 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:17 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:17 volumio-pi5 kernel: usb 1-2: USB disconnect, device number 90 Aug 29 16:12:17 volumio-pi5 kernel: usb 1-2: new high-speed USB device number 91 using xhci-hcd Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2: Product: USB2.0 HUB Aug 29 16:12:18 volumio-pi5 kernel: hub 1-2:1.0: USB hub found Aug 29 16:12:18 volumio-pi5 kernel: hub 1-2:1.0: 4 ports detected Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2.2: new full-speed USB device number 92 using xhci-hcd Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2.2: New USB device found, idVendor=222a, idProduct=0001, bcdDevice= 0.02 Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2.2: Product: ILITEK-TP Aug 29 16:12:18 volumio-pi5 kernel: usb 1-2.2: Manufacturer: ILITEK Aug 29 16:12:18 volumio-pi5 kernel: input: ILITEK ILITEK-TP as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0003/input/input14 Aug 29 16:12:18 volumio-pi5 kernel: input: ILITEK ILITEK-TP Mouse as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:222A:0001.0003/input/input16 Aug 29 16:12:18 volumio-pi5 kernel: hid-multitouch 0003:222A:0001.0003: input,hiddev96,hidraw0: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-xhci-hcd.0-2.2/input0 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::servicePause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: [1787998339359] ControllerQobuz::pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand pause took 3 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 2 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 2 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 2 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":57107,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1551 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":57107,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1551 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":57107,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1551 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.397+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PAUSED positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.398+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PAUSED positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.398+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PAUSED positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.398+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PAUSED positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.398+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PAUSED positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.398+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PAUSED positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 46ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 46ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 45ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPlay Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::play index undefined Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::startPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: [1787998339487] ControllerQobuz::resume Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::resume Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand play Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand play took 49 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 1 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 0 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 1 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58229,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1649 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService play Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58229,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1649 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService play Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Received an update from plugin. extracting info from payload Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.550+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.551+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.554+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.554+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.554+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.555+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 28ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 28ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58229,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1649 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService play Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Received an update from plugin. extracting info from payload Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.571+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.571+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.572+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.572+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56115 volume=100 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 39ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::servicePause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: [1787998339666] ControllerQobuz::pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand pause took 3 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Aug 29 16:12:19 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 4 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 3 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 3 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":58355,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1649 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":58355,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1649 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":58355,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1649 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:19 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.693+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PAUSED positionMs=56234 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.693+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PAUSED positionMs=56234 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.693+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PAUSED positionMs=56234 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.694+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PAUSED positionMs=56234 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.694+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PAUSED positionMs=56234 volume=100 Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.694+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PAUSED positionMs=56234 volume=100 Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 39ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 37ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: ------------------------------ 37ms Aug 29 16:12:19 volumio-pi5 volumio[1282]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.742+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.742+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.801+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.801+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.921+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:19 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:19.922+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.221+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.221+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.281+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.281+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.401+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.402+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPlay Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::play index undefined Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::startPlaybackTimer Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: [1787998340406] ControllerQobuz::resume Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ControllerMpd::resume Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand play Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.476+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.476+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Aug 29 16:12:20 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: sendMpdCommand play took 74 milliseconds Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Aug 29 16:12:20 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Aug 29 16:12:20 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ControllerMpd::getState Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 1 milliseconds Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 1 milliseconds Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: sendMpdCommand status took 1 milliseconds Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseState Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59351,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1513 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService play Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59351,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1513 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService play Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Received an update from plugin. extracting info from payload Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ControllerMpd::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::servicePushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59351,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1513 Kbps","isStreaming":false,"title":"file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8611504&eid=86108531&fmt=7&profile=raw&app_id=539451548&cid=4580033&etsp=1788001881&hmac=GzBUI_BGbIhvoQWyBoz73HH_Ydg","trackType":"qobuz"} Aug 29 16:12:20 volumio-pi5 volumio[1282]: verbose: CURRENT POSITION 1 Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState stateService play Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Received an update from plugin. extracting info from payload Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.512+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.512+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.513+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.513+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.513+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.512+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.514+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.514+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.514+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.514+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_PLAYING positionMs=56615 volume=100 Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.521+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.521+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ------------------------------ 48ms Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ------------------------------ 47ms Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: ------------------------------ 47ms Aug 29 16:12:20 volumio-pi5 volumio[1282]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.701+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.701+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.852+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.852+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.911+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:20 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:20.911+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.573+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.573+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.662+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.662+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.903+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.903+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.962+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:21 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:21.962+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:22 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:22.022+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:12:22 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:12:22.022+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:12:36 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 16:12:36 volumio-pi5 volumio[1282]: info: CURURI: music-library Aug 29 16:12:49 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 29 16:12:50 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand update Aug 29 16:12:50 volumio-pi5 volumio[1282]: info: sendMpdCommand update took 15 milliseconds Aug 29 16:12:52 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 16:12:52 volumio-pi5 volumio[1282]: info: CURURI: music-library/USB Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::ClearQueue Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::stop Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::updateTrackBlock Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrackBlock Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::pushState Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushState Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioGetState Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::serviceStop Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CorePlayQueue::getTrack 1 Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreCommandRouter::serviceStop Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: [1787998375150] ControllerQobuz::stop Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: ControllerMpd::stop Aug 29 16:12:55 volumio-pi5 volumio[1282]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CorePlayQueue::clearPlayQueue Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CorePlayQueue::saveQueue Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreCommandRouter::volumioPushQueue Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CoreStateMachine::addQueueItems Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: CorePlayQueue::addQueueItems Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: Preload queue cleared Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: Adding Item to queue: music-library/USB/MUSIC_DRIVE Aug 29 16:12:55 volumio-pi5 volumio[1282]: info: Exploding uri music-library/USB/MUSIC_DRIVE in service mpd Aug 29 16:13:16 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:13:16.056+06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 16:13:16 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:13:16.056+06:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: Aug 29 16:13:16 volumio-pi5 volumio[1282]: ---------------------------- MPD announces state update: player Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F01.My_Funny_Valentine_(alternate_take).flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/01.My_Funny_Valentine_(alternate_take).flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F02.My_Funny_Valentine.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/02.My_Funny_Valentine.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F03.I_Hear_a_Rhapsody.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/03.I_Hear_a_Rhapsody.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F04.Dream_Gypsy.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/04.Dream_Gypsy.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F05.Stairway_to_the_Stars.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/05.Stairway_to_the_Stars.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F06.Im_Getting_Sentimental_Over_You.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/06.Im_Getting_Sentimental_Over_You.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F07.Romain.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/07.Romain.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F08.Romain_(alternate_take).flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/08.Romain_(alternate_take).flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F09.Skating_in_Central_Park.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/09.Skating_in_Central_Park.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Bill%20Evans%20%26%20Jim%20Hall/Undercurrent/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FBill_Evans_%26_Jim_Hall-Undercurrent%2F10.Darn_That_Dream.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/Bill_Evans_&_Jim_Hall-Undercurrent/10.Darn_That_Dream.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F01%20-%20Daydreamer.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/01 - Daydreamer.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F02%20-%20Best%20for%20last.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/02 - Best for last.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F03%20-%20Chasing%20pavements.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/03 - Chasing pavements.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F04%20-%20Cold%20shoulder.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/04 - Cold shoulder.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F05%20-%20Crazy%20for%20you.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/05 - Crazy for you.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F06%20-%20Melt%20my%20heart%20to%20stone.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/06 - Melt my heart to stone.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F07%20-%20First%20love.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/07 - First love.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F08%20-%20Right%20as%20rain.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/08 - Right as rain.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F09%20-%20Make%20you%20feel%20my%20love.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/09 - Make you feel my love.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F10%20-%20My%20same.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/10 - My same.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F11%20-%20Tired.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/11 - Tired.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD1%2F12%20-%20Hometown%20glory.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD1/12 - Hometown glory.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F01%20-%20Chasing%20Pavements.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/01 - Chasing Pavements.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F02%20-%20Melt%20My%20Heart%20To%20Stone.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/02 - Melt My Heart To Stone.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F03%20-%20That's%20It%20I%20Quit%20I'm%20Movin'%20On.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/03 - That's It I Quit I'm Movin' On.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F04%20-%20Crazy%20for%20You.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/04 - Crazy for You.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F05%20-%20Right%20As%20Rain.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/05 - Right As Rain.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F06%20-%20My%20Same.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/06 - My Same.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F07%20-%20Make%20You%20Feel%20My%20Love.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/07 - Make You Feel My Love.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F08%20-%20Daydreamer.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/08 - Daydreamer.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F09%20-%20Hometown%20Glory.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/09 - Hometown Glory.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F10%20-%20Many%20Shades%20Of%20Black.flac&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/10 - Many Shades Of Black.flac Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F19%20%7BBonus%20Disc%7D.cue&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/19 {Bonus Disc}.cue Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: ALBUMART /albumart?cacheid=662&web=Adele/19%20%7BBonus%20Disc%7D/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_DRIVE%2FLOSSLESS%2FAdele%2FAdele%20-%2019%20(2008)%20%5BFLAC%5D%20%7BExpanded%20Edition%202CD%7D%2FCD2%2F19%20%7BBonus%20Disc%7D.cue&metadata=false Aug 29 16:13:16 volumio-pi5 volumio[1282]: info: URI /mnt/USB/MUSIC_DRIVE/LOSSLESS/Adele/Adele - 19 (2008) [FLAC] {Expanded Edition 2CD}/CD2/19 {Bonus Disc}.cue Aug 29 16:13:16 volumio-pi5 volumio[1282]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:13:16 volumio-pi5 volumio[1282]: Error: Unable to resolve or reject the same promise twice Aug 29 16:13:16 volumio-pi5 volumio[1282]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 29 16:13:16 volumio-pi5 volumio[1282]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at Socket.emit (node:events:514:28) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at addChunk (node:internal/streams/readable:343:12) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at Readable.push (node:internal/streams/readable:253:10) Aug 29 16:13:16 volumio-pi5 volumio[1282]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 29 16:13:16 volumio-pi5 volumio[1282]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:13:16 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:13:16.171+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39878,00:00:00:00:00:00%02 @ 0x2c01380" id=qobuz://song/86108531 title=Festival Aug 29 16:13:16 volumio-pi5 volumio5-onboarding[1536]: time=2026-08-29T16:13:16.172+06:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2f486f0" id=qobuz://song/86108531 title=Festival Aug 29 16:13:16 volumio-pi5 sudo[3798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 16:12' Aug 29 16:13:16 volumio-pi5 sudo[3798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"