-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Sat 2025-12-27 18:44:26 JST. -- Dec 27 18:43:03 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:03.679+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.94:61198 Dec 27 18:43:03 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 18:43:05 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 18:43:05 motivo volumio[1005]: info: CURURI: music-library Dec 27 18:43:05 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:06 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 18:43:06 motivo volumio[1005]: info: CURURI: music-library/USB Dec 27 18:43:06 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:07 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 18:43:07 motivo volumio[1005]: info: CURURI: music-library/USB/406B-C05E Dec 27 18:43:07 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:12 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 18:43:12 motivo volumio[1005]: info: CURURI: music-library/USB/406B-C05E/Electricity Dec 27 18:43:12 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:12 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/Electricity/._01_01_Electricity (Hannett_Cargo Studios Version _ Remastered).flac Dec 27 18:43:12 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/Electricity/._01_02_Almost (Vince Clarke Remix).flac Dec 27 18:43:12 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/Electricity/01_01_Electricity (Hannett_Cargo Studios Version _ Remastered).flac Dec 27 18:43:12 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/Electricity/01_02_Almost (Vince Clarke Remix).flac Dec 27 18:43:12 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/Electricity/._01_01_Electricity (Hannett_Cargo Studios Version _ Remastered).flac in service mpd Dec 27 18:43:12 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FElectricity%2F._01_01_Electricity%20(Hannett_Cargo%20Studios%20Version%20_%20Remastered).flac&metadata=false Dec 27 18:43:12 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/Electricity/._01_01_Electricity (Hannett_Cargo Studios Version _ Remastered).flac Dec 27 18:43:12 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/Electricity/._01_02_Almost (Vince Clarke Remix).flac in service mpd Dec 27 18:43:12 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FElectricity%2F._01_02_Almost%20(Vince%20Clarke%20Remix).flac&metadata=false Dec 27 18:43:12 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/Electricity/._01_02_Almost (Vince Clarke Remix).flac Dec 27 18:43:12 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/Electricity/01_01_Electricity (Hannett_Cargo Studios Version _ Remastered).flac in service mpd Dec 27 18:43:12 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=%E3%82%AA%E3%83%BC%E3%82%B1%E3%82%B9%E3%83%88%E3%83%A9%E3%83%AB%E3%83%BB%E3%83%9E%E3%83%8C%E3%83%BC%E3%83%B4%E3%82%A1%E3%83%BC%E3%82%BA%E3%83%BB%E3%82%A4%E3%83%B3%E3%83%BB%E3%82%B6%E3%83%BB%E3%83%80%E3%83%BC%E3%82%AF/Electricity/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FElectricity%2F01_01_Electricity%20(Hannett_Cargo%20Studios%20Version%20_%20Remastered).flac&metadata=false Dec 27 18:43:12 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/Electricity/01_01_Electricity (Hannett_Cargo Studios Version _ Remastered).flac Dec 27 18:43:12 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/Electricity/01_02_Almost (Vince Clarke Remix).flac in service mpd Dec 27 18:43:12 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=%E3%82%AA%E3%83%BC%E3%82%B1%E3%82%B9%E3%83%88%E3%83%A9%E3%83%AB%E3%83%BB%E3%83%9E%E3%83%8C%E3%83%BC%E3%83%B4%E3%82%A1%E3%83%BC%E3%82%BA%E3%83%BB%E3%82%A4%E3%83%B3%E3%83%BB%E3%82%B6%E3%83%BB%E3%83%80%E3%83%BC%E3%82%AF/Electricity/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FElectricity%2F01_02_Almost%20(Vince%20Clarke%20Remix).flac&metadata=false Dec 27 18:43:12 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/Electricity/01_02_Almost (Vince Clarke Remix).flac Dec 27 18:43:18 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:18.646+09:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.94:60706 error="read tcp 192.168.0.97:7331->192.168.0.94:60706: read: connection reset by peer" Dec 27 18:43:18 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:18.646+09:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.94:60706 Dec 27 18:43:18 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:18.647+09:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.94:60706 Dec 27 18:43:26 motivo dhcpcd[931]: wlan0: carrier lost Dec 27 18:43:26 motivo wpa_supplicant[920]: wlan0: Associated with 86:01:12:16:20:a3 Dec 27 18:43:26 motivo wpa_supplicant[920]: wlan0: CTRL-EVENT-CONNECTED - Connection to 86:01:12:16:20:a3 completed [id=0 id_str=] Dec 27 18:43:26 motivo wpa_supplicant[920]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 27 18:43:26 motivo avahi-daemon[705]: Withdrawing address record for 192.168.0.97 on wlan0. Dec 27 18:43:26 motivo avahi-daemon[705]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.97. Dec 27 18:43:26 motivo avahi-daemon[705]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 27 18:43:26 motivo dhcpcd[931]: wlan0: deleting route to 192.168.0.0/24 Dec 27 18:43:26 motivo dhcpcd[931]: wlan0: deleting default route via 192.168.0.1 Dec 27 18:43:26 motivo volumio[1005]: info: Discovery: A device disappeared from network Dec 27 18:43:26 motivo volumio[1005]: info: Discovery: Device motivo disappeared from network Dec 27 18:43:26 motivo dhcpcd[931]: wlan0: carrier acquired Dec 27 18:43:26 motivo dhcpcd[931]: wlan0: IAID 67:9f:fa:1f Dec 27 18:43:27 motivo volumio[1005]: info: Received Get System Info Dec 27 18:43:27 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:43:27 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:43:27 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:43:27 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:27 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:27 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:43:27 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 18:43:27 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 18:43:27 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:27.384+09:00 level=INFO msg="BLE descriptor updated" deviceId=9a852ee443e30a8316777199caad1848 deviceName=Motivo deviceModel=0x1c8a038 Dec 27 18:43:27 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:27.386+09:00 level=INFO msg="mDNS descriptor updated" deviceId=9a852ee443e30a8316777199caad1848 deviceName=Motivo deviceModel=0x1c8a038 Dec 27 18:43:27 motivo dhcpcd[931]: wlan0: rebinding lease of 192.168.0.97 Dec 27 18:43:27 motivo dhcpcd[931]: wlan0: soliciting an IPv6 router Dec 27 18:43:28 motivo ntpd[811]: Deleting interface #12 wlan0, 192.168.0.97#123, interface stats: received=45, sent=48, dropped=0, active_time=120 secs Dec 27 18:43:28 motivo ntpd[811]: 153.127.38.46 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 45.77.20.103 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 162.159.200.1 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 142.91.108.61 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 162.159.200.123 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 139.64.134.57 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 129.250.35.251 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 85.121.50.82 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 129.250.35.250 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 47.79.37.210 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 167.179.119.205 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 142.91.105.55 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 43.229.16.149 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 5.104.87.198 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 103.131.151.30 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 110.4.129.114 local addr 192.168.0.97 -> Dec 27 18:43:28 motivo ntpd[811]: 172.104.124.149 local addr 192.168.0.97 -> Dec 27 18:43:31 motivo dhcpcd[931]: wlan0: probing address 192.168.0.97/24 Dec 27 18:43:31 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:31 motivo kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Dec 27 18:43:33 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:33.140+09:00 level=INFO msg="new address was allocated" component=conn/ble old=13 new=14 Dec 27 18:43:33 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:33 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:33 motivo kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Dec 27 18:43:33 motivo volumio[1005]: info: Reporting MCU Network Status: 0 Dec 27 18:43:33 motivo volumio[1005]: info: Volumio Network Manager: Network status updated: 0 Dec 27 18:43:34 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:34.880+09:00 level=INFO msg="new address was allocated" component=conn/ble old=14 new=15 Dec 27 18:43:35 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:35 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:35 motivo kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Dec 27 18:43:36 motivo dhcpcd[931]: wlan0: leased 192.168.0.97 for 86400 seconds Dec 27 18:43:36 motivo avahi-daemon[705]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.97. Dec 27 18:43:36 motivo avahi-daemon[705]: New relevant interface wlan0.IPv4 for mDNS. Dec 27 18:43:36 motivo dhcpcd[931]: wlan0: adding route to 192.168.0.0/24 Dec 27 18:43:36 motivo avahi-daemon[705]: Registering new address record for 192.168.0.97 on wlan0.IPv4. Dec 27 18:43:36 motivo dhcpcd[931]: wlan0: adding default route via 192.168.0.1 Dec 27 18:43:36 motivo volumio[1005]: info: MRS: Found cast device: XA401-67447ac7ea09789f64c652c4faaad788 Dec 27 18:43:36 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:36.679+09:00 level=INFO msg="new address was allocated" component=conn/ble old=15 new=16 Dec 27 18:43:36 motivo volumio[1005]: info: Received Get System Info Dec 27 18:43:36 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:43:36 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:43:36 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:43:36 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:36 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:36 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:43:36 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 18:43:36 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 18:43:36 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:36.828+09:00 level=INFO msg="BLE descriptor updated" deviceId=9a852ee443e30a8316777199caad1848 deviceName=Motivo deviceModel=0x1cbc038 Dec 27 18:43:36 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:36.831+09:00 level=INFO msg="mDNS descriptor updated" deviceId=9a852ee443e30a8316777199caad1848 deviceName=Motivo deviceModel=0x1cbc038 Dec 27 18:43:36 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:37 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:37 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:37.119+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:37 motivo kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Dec 27 18:43:37 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:37.325+09:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.94:61198 Dec 27 18:43:37 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:37.325+09:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.94:61198 Dec 27 18:43:37 motivo volumio[1005]: info: Discovery: adding 651f190f-b0b9-4a24-bb1a-e4aae9419a88 Dec 27 18:43:37 motivo volumio[1005]: info: Discovery: Found device Motivo Dec 27 18:43:37 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:37 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:37 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:37.420+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:37 motivo ntpd[811]: Listen normally on 13 wlan0 192.168.0.97:123 Dec 27 18:43:37 motivo ntpd[811]: new interface(s) found: waking up resolver Dec 27 18:43:37 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:37.721+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:37 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:37.788+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.94:61209 Dec 27 18:43:37 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:37.790+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.94:61216 Dec 27 18:43:38 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:38.022+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:38 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:38.323+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:38 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:38.389+09:00 level=INFO msg="new address was allocated" component=conn/ble old=16 new=17 Dec 27 18:43:38 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:38.624+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:38 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:38 motivo ntpd[811]: 129.250.35.250 local addr 192.168.0.97 -> Dec 27 18:43:38 motivo ntpd[811]: Soliciting pool server 103.131.151.20 Dec 27 18:43:38 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:38 motivo kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Dec 27 18:43:38 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:38.924+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:39 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:39.226+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:39 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:39.527+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:39 motivo ntpd[811]: 47.79.37.210 local addr 192.168.0.97 -> Dec 27 18:43:39 motivo ntpd[811]: 142.91.108.61 local addr 192.168.0.97 -> Dec 27 18:43:39 motivo ntpd[811]: Soliciting pool server 160.25.135.55 Dec 27 18:43:39 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:39.827+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:40 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:40.069+09:00 level=INFO msg="new address was allocated" component=conn/ble old=17 new=18 Dec 27 18:43:40 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:40.128+09:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=12 Dec 27 18:43:40 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:40.128+09:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=12 chunks=1 index=0 Dec 27 18:43:40 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:40 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:40 motivo kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Dec 27 18:43:40 motivo ntpd[811]: 129.250.35.251 local addr 192.168.0.97 -> Dec 27 18:43:40 motivo ntpd[811]: Soliciting pool server 108.160.132.224 Dec 27 18:43:41 motivo ntpd[811]: 139.64.134.57 local addr 192.168.0.97 -> Dec 27 18:43:41 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:41.869+09:00 level=INFO msg="new address was allocated" component=conn/ble old=18 new=19 Dec 27 18:43:42 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:42 motivo dbus-daemon[712]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=1483 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=789 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 27 18:43:42 motivo ntpd[811]: 142.91.105.55 local addr 192.168.0.97 -> Dec 27 18:43:43 motivo volumio[1005]: info: Reporting MCU Network Status: 2 Dec 27 18:43:43 motivo volumio[1005]: info: Volumio Network Manager: Network status updated: 2 Dec 27 18:43:46 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:43:46 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:43:46 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:43:46 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:46 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:46 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:43:46 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97:3000 from 192.168.0.94 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 27 18:43:46 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 18:43:46 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 18:43:47 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:47.056+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.94:61340 Dec 27 18:43:47 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.94 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 18:43:47 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.94 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Dec 27 18:43:48 motivo volumio5-onboarding[1483]: time=2025-12-27T18:43:48.830+09:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.94:61340 @ 0x19b0b40" latency=-25.473705ms timeout=10s from=APP_PAGE_ROOT Dec 27 18:43:49 motivo sudo[7370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 18:43:49 motivo sudo[7370]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:43:49 motivo sudo[7370]: pam_unix(sudo:session): session closed for user root Dec 27 18:43:49 motivo sudo[7373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 18:43:49 motivo sudo[7373]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:43:49 motivo sudo[7373]: pam_unix(sudo:session): session closed for user root Dec 27 18:43:49 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.94 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 18:43:49 motivo sudo[7376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 18:43:49 motivo sudo[7376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:43:49 motivo sudo[7376]: pam_unix(sudo:session): session closed for user root Dec 27 18:43:49 motivo sudo[7379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 18:43:49 motivo sudo[7379]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:43:49 motivo sudo[7379]: pam_unix(sudo:session): session closed for user root Dec 27 18:43:49 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.94 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:49 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::volumioGetQueue Dec 27 18:43:49 motivo volumio[1005]: info: CoreStateMachine::getQueue Dec 27 18:43:49 motivo volumio[1005]: info: CorePlayQueue::getQueue Dec 27 18:43:49 motivo volumio[1005]: info: Listing playlists Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 18:43:49 motivo volumio[1005]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 27 18:43:49 motivo volumio[1005]: info: Received Get System Info Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:43:49 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:49 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:49 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 18:43:49 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 18:43:54 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 27 18:43:54 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 18:43:54 motivo volumio[1005]: info: Received Get System Info Dec 27 18:43:54 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:43:54 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:43:54 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:43:54 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:54 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:54 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:43:54 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 18:43:54 motivo volumio[1005]: info: CURURI: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition] Dec 27 18:43:54 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_02_POKALA.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_03_GLASS CHIME.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_04_PON.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_05_WASSER.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_06_MIZUE.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_07_COLLECTING NET.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_08_APPLE STAR.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_09_MEINE REFLEXION.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_10_8・31.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_01_SHUFFER.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_02_POKALA.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_03_GLASS CHIME.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_04_PON.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_05_WASSER.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_06_MIZUE.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_07_COLLECTING NET.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_08_APPLE STAR.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_09_MEINE REFLEXION.flac Dec 27 18:43:54 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_10_8・31.flac Dec 27 18:43:54 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac in service mpd Dec 27 18:43:54 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_01_SHUFFER.flac&metadata=false Dec 27 18:43:54 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:43:54 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_02_POKALA.flac in service mpd Dec 27 18:43:54 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_02_POKALA.flac&metadata=false Dec 27 18:43:54 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_02_POKALA.flac Dec 27 18:43:54 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_03_GLASS CHIME.flac in service mpd Dec 27 18:43:54 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_03_GLASS%20CHIME.flac&metadata=false Dec 27 18:43:54 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_03_GLASS CHIME.flac Dec 27 18:43:54 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_04_PON.flac in service mpd Dec 27 18:43:54 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_04_PON.flac&metadata=false Dec 27 18:43:54 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_04_PON.flac Dec 27 18:43:54 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_05_WASSER.flac in service mpd Dec 27 18:43:54 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_05_WASSER.flac&metadata=false Dec 27 18:43:54 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_05_WASSER.flac Dec 27 18:43:54 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_06_MIZUE.flac in service mpd Dec 27 18:43:54 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_06_MIZUE.flac&metadata=false Dec 27 18:43:54 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_06_MIZUE.flac Dec 27 18:43:54 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_07_COLLECTING NET.flac in service mpd Dec 27 18:43:54 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_07_COLLECTING%20NET.flac&metadata=false Dec 27 18:43:54 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_07_COLLECTING NET.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_08_APPLE STAR.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_08_APPLE%20STAR.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_08_APPLE STAR.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_09_MEINE REFLEXION.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_09_MEINE%20REFLEXION.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_09_MEINE REFLEXION.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_10_8・31.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F._01_10_8%E3%83%BB31.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_10_8・31.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_01_SHUFFER.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_01_SHUFFER.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_01_SHUFFER.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_02_POKALA.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_02_POKALA.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_02_POKALA.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_03_GLASS CHIME.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_03_GLASS%20CHIME.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_03_GLASS CHIME.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_04_PON.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_04_PON.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_04_PON.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_05_WASSER.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_05_WASSER.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_05_WASSER.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_06_MIZUE.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_06_MIZUE.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_06_MIZUE.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_07_COLLECTING NET.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_07_COLLECTING%20NET.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_07_COLLECTING NET.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_08_APPLE STAR.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_08_APPLE%20STAR.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_08_APPLE STAR.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_09_MEINE REFLEXION.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_09_MEINE%20REFLEXION.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_09_MEINE REFLEXION.flac Dec 27 18:43:55 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_10_8・31.flac in service mpd Dec 27 18:43:55 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=INOYAMALAND/DANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D/extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2FDANZINDAN-POJIDON%20%5BNew%20Master%20Edition%5D%2F01_10_8%E3%83%BB31.flac&metadata=false Dec 27 18:43:55 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_10_8・31.flac Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 18:43:56 motivo volumio[1005]: info: Received Get System Info Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:43:56 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:43:56 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::ClearQueue Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::stPlaybackTimer Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::updateTrackBlock Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrackBlock Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::serviceStop Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::serviceStop Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::stop Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::clearPlayQueue Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::addQueueItems Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::addQueueItems Dec 27 18:43:56 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::updateTrackBlock Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrackBlock Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPlay Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::play index 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::addQueueItems Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::addQueueItems Dec 27 18:43:56 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_02_POKALA.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_02_POKALA.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_03_GLASS CHIME.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_03_GLASS CHIME.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_04_PON.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_04_PON.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_05_WASSER.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_05_WASSER.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_06_MIZUE.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_06_MIZUE.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_07_COLLECTING NET.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_07_COLLECTING NET.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_08_APPLE STAR.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_08_APPLE STAR.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_09_MEINE REFLEXION.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_09_MEINE REFLEXION.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_10_8・31.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_10_8・31.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_01_SHUFFER.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_01_SHUFFER.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_02_POKALA.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_02_POKALA.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_03_GLASS CHIME.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_03_GLASS CHIME.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_04_PON.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_04_PON.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_05_WASSER.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_05_WASSER.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_06_MIZUE.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_06_MIZUE.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_07_COLLECTING NET.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_07_COLLECTING NET.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_08_APPLE STAR.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_08_APPLE STAR.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_09_MEINE REFLEXION.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_09_MEINE REFLEXION.flac Dec 27 18:43:56 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_10_8・31.flac Dec 27 18:43:56 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_10_8・31.flac Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::play index undefined Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::updateTrackBlock Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrackBlock Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::startPlaybackTimer Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::clearAddPlayTracks USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand stop took 66 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand stop took 20 milliseconds Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:43:56 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand status took 5 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand clear took 6 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand status took 4 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand status took 3 milliseconds Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand add "USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac" Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: 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 27 18:43:56 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: No code Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: 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 27 18:43:56 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: No code Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 47ms Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 47ms Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:43:56 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:43:56 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:43:56 motivo volumio[1005]: error: updateQueue error: null Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:43:56 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 54ms Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand add "USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac" took 52 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 10ms Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 9ms Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand play Dec 27 18:43:56 motivo volumio[1005]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 62ms Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:43:56 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:43:56 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 37ms Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand play took 35 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 34ms Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:43:56 motivo volumio[1005]: info: Dec 27 18:43:56 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand status took 19 milliseconds Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand status took 20 milliseconds Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand status took 18 milliseconds Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand clearerror took 26 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand clearerror took 24 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand clearerror took 23 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 27 18:43:56 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:43:56 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01_01_SHUFFER.flac","artist":null,"album":null,"uri":"USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac","trackType":"flac"} Dec 27 18:43:56 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: No code Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01_01_SHUFFER.flac","artist":null,"album":null,"uri":"USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac","trackType":"flac"} Dec 27 18:43:56 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: No code Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01_01_SHUFFER.flac","artist":null,"album":null,"uri":"USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac","trackType":"flac"} Dec 27 18:43:56 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: No code Dec 27 18:43:56 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:43:56 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:43:56 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:43:56 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 116ms Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 113ms Dec 27 18:43:56 motivo volumio[1005]: info: ------------------------------ 112ms Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:43:56 motivo volumio[1005]: info: MCU Signalled Playback Inactive Dec 27 18:43:59 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 18:44:00 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::ClearQueue Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::clearPlayQueue Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::addQueueItems Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::addQueueItems Dec 27 18:44:00 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::updateTrackBlock Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrackBlock Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPlay Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::play index 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::addQueueItems Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::addQueueItems Dec 27 18:44:00 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_02_POKALA.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_02_POKALA.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_03_GLASS CHIME.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_03_GLASS CHIME.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_04_PON.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_04_PON.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_05_WASSER.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_05_WASSER.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_06_MIZUE.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_06_MIZUE.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_07_COLLECTING NET.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_07_COLLECTING NET.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_08_APPLE STAR.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_08_APPLE STAR.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_09_MEINE REFLEXION.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_09_MEINE REFLEXION.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_10_8・31.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_10_8・31.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_01_SHUFFER.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_01_SHUFFER.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_02_POKALA.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_02_POKALA.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_03_GLASS CHIME.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_03_GLASS CHIME.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_04_PON.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_04_PON.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_05_WASSER.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_05_WASSER.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_06_MIZUE.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_06_MIZUE.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_07_COLLECTING NET.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_07_COLLECTING NET.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_08_APPLE STAR.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_08_APPLE STAR.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_09_MEINE REFLEXION.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_09_MEINE REFLEXION.flac Dec 27 18:44:00 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_10_8・31.flac Dec 27 18:44:00 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/01_10_8・31.flac Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::play index undefined Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::updateTrackBlock Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrackBlock Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::startPlaybackTimer Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::clearAddPlayTracks USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand stop took 13 milliseconds Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:00 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand clear took 2 milliseconds Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand add "USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac" Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:00 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:00 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:00 motivo volumio[1005]: error: updateQueue error: null Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:00 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 6ms Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand add "USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac" took 6 milliseconds Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 4ms Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 3ms Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand play Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:00 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:00 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 5ms Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand play took 4 milliseconds Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 3ms Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:44:00 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:44:00 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:44:00 motivo volumio[1005]: info: Dec 27 18:44:00 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:44:00 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand status took 5 milliseconds Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand status took 5 milliseconds Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand status took 11 milliseconds Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand clearerror took 9 milliseconds Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand clearerror took 8 milliseconds Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand clearerror took 7 milliseconds Dec 27 18:44:00 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:44:00 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:44:00 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01_01_SHUFFER.flac","artist":null,"album":null,"uri":"USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac","trackType":"flac"} Dec 27 18:44:00 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: No code Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01_01_SHUFFER.flac","artist":null,"album":null,"uri":"USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac","trackType":"flac"} Dec 27 18:44:00 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: No code Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01_01_SHUFFER.flac","artist":null,"album":null,"uri":"USB/406B-C05E/DANZINDAN-POJIDON [New Master Edition]/._01_01_SHUFFER.flac","trackType":"flac"} Dec 27 18:44:00 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: No code Dec 27 18:44:00 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:00 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:00 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:00 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 64ms Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 63ms Dec 27 18:44:00 motivo volumio[1005]: info: ------------------------------ 63ms Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:00 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:04 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 18:44:04 motivo volumio[1005]: info: CURURI: music-library/USB/406B-C05E/101 Dec 27 18:44:04 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._03 Strangelove_24BIT_003.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._04 Sacred_24BIT_004.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._05 Something To Do_24BIT_005.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._07 Stripped_24BIT_007.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._08 Somebody_24BIT_008.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._09 Things You Said_24BIT_009.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._10 Black Celebration_24BIT_010.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._11 Shake The Disease_24BIT_011.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._12 Nothing_24BIT_012.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._13 Pleasure Little Treasure_24BIT_013.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._14 People Are People_24BIT_014.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._15 A Question Of Time_24BIT_015.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._16 Never Let Me Down Again_24BIT_016.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._17 A Question Of Lust_24BIT_017.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._18 Master And Servant_24BIT_018.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._19 Just Can't Get Enough_24BIT_019.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/._20 Everything Counts_24BIT_020.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/01 Pimpf_24BIT_001.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/03 Strangelove_24BIT_003.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/04 Sacred_24BIT_004.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/05 Something To Do_24BIT_005.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/07 Stripped_24BIT_007.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/08 Somebody_24BIT_008.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/09 Things You Said_24BIT_009.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/10 Black Celebration_24BIT_010.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/11 Shake The Disease_24BIT_011.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/12 Nothing_24BIT_012.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/13 Pleasure Little Treasure_24BIT_013.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/14 People Are People_24BIT_014.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/15 A Question Of Time_24BIT_015.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/16 Never Let Me Down Again_24BIT_016.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/17 A Question Of Lust_24BIT_017.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/18 Master And Servant_24BIT_018.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/19 Just Can't Get Enough_24BIT_019.flac Dec 27 18:44:04 motivo volumio[1005]: info: Preloading song: music-library/USB/406B-C05E/101/20 Everything Counts_24BIT_020.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._01%20Pimpf_24BIT_001.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._02 Behind The Wheel_24BIT_002.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._02%20Behind%20The%20Wheel_24BIT_002.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._03 Strangelove_24BIT_003.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._03%20Strangelove_24BIT_003.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._03 Strangelove_24BIT_003.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._04 Sacred_24BIT_004.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._04%20Sacred_24BIT_004.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._04 Sacred_24BIT_004.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._05 Something To Do_24BIT_005.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._05%20Something%20To%20Do_24BIT_005.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._05 Something To Do_24BIT_005.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._06 Blasphemous Rumours_24BIT_006.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._06%20Blasphemous%20Rumours_24BIT_006.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._07 Stripped_24BIT_007.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._07%20Stripped_24BIT_007.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._07 Stripped_24BIT_007.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._08 Somebody_24BIT_008.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._08%20Somebody_24BIT_008.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._08 Somebody_24BIT_008.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._09 Things You Said_24BIT_009.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._09%20Things%20You%20Said_24BIT_009.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._09 Things You Said_24BIT_009.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._10 Black Celebration_24BIT_010.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._10%20Black%20Celebration_24BIT_010.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._10 Black Celebration_24BIT_010.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._11 Shake The Disease_24BIT_011.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._11%20Shake%20The%20Disease_24BIT_011.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._11 Shake The Disease_24BIT_011.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._12 Nothing_24BIT_012.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._12%20Nothing_24BIT_012.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._12 Nothing_24BIT_012.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._13 Pleasure Little Treasure_24BIT_013.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._13%20Pleasure%20Little%20Treasure_24BIT_013.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._13 Pleasure Little Treasure_24BIT_013.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._14 People Are People_24BIT_014.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._14%20People%20Are%20People_24BIT_014.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._14 People Are People_24BIT_014.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._15 A Question Of Time_24BIT_015.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._15%20A%20Question%20Of%20Time_24BIT_015.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._15 A Question Of Time_24BIT_015.flac Dec 27 18:44:04 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._16 Never Let Me Down Again_24BIT_016.flac in service mpd Dec 27 18:44:04 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._16%20Never%20Let%20Me%20Down%20Again_24BIT_016.flac&metadata=false Dec 27 18:44:04 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._16 Never Let Me Down Again_24BIT_016.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._17 A Question Of Lust_24BIT_017.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._17%20A%20Question%20Of%20Lust_24BIT_017.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._17 A Question Of Lust_24BIT_017.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._18 Master And Servant_24BIT_018.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._18%20Master%20And%20Servant_24BIT_018.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._18 Master And Servant_24BIT_018.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._19 Just Can't Get Enough_24BIT_019.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._19%20Just%20Can't%20Get%20Enough_24BIT_019.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._19 Just Can't Get Enough_24BIT_019.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/._20 Everything Counts_24BIT_020.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F._20%20Everything%20Counts_24BIT_020.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/._20 Everything Counts_24BIT_020.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/01 Pimpf_24BIT_001.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F01%20Pimpf_24BIT_001.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/01 Pimpf_24BIT_001.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/02 Behind The Wheel_24BIT_002.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F02%20Behind%20The%20Wheel_24BIT_002.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/03 Strangelove_24BIT_003.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F03%20Strangelove_24BIT_003.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/03 Strangelove_24BIT_003.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/04 Sacred_24BIT_004.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F04%20Sacred_24BIT_004.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/04 Sacred_24BIT_004.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/05 Something To Do_24BIT_005.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F05%20Something%20To%20Do_24BIT_005.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/05 Something To Do_24BIT_005.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/06 Blasphemous Rumours_24BIT_006.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F06%20Blasphemous%20Rumours_24BIT_006.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/07 Stripped_24BIT_007.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F07%20Stripped_24BIT_007.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/07 Stripped_24BIT_007.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/08 Somebody_24BIT_008.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F08%20Somebody_24BIT_008.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/08 Somebody_24BIT_008.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/09 Things You Said_24BIT_009.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F09%20Things%20You%20Said_24BIT_009.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/09 Things You Said_24BIT_009.flac Dec 27 18:44:05 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::ClearQueue Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::clearPlayQueue Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::addQueueItems Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::addQueueItems Dec 27 18:44:05 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::updateTrackBlock Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrackBlock Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPlay Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::play index 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::addQueueItems Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::addQueueItems Dec 27 18:44:05 motivo volumio[1005]: info: Preload queue cleared Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._03 Strangelove_24BIT_003.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._03 Strangelove_24BIT_003.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._04 Sacred_24BIT_004.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._04 Sacred_24BIT_004.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._05 Something To Do_24BIT_005.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._05 Something To Do_24BIT_005.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._07 Stripped_24BIT_007.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._07 Stripped_24BIT_007.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._08 Somebody_24BIT_008.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._08 Somebody_24BIT_008.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._09 Things You Said_24BIT_009.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._09 Things You Said_24BIT_009.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._10 Black Celebration_24BIT_010.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._10 Black Celebration_24BIT_010.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._11 Shake The Disease_24BIT_011.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._11 Shake The Disease_24BIT_011.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._12 Nothing_24BIT_012.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._12 Nothing_24BIT_012.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._13 Pleasure Little Treasure_24BIT_013.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._13 Pleasure Little Treasure_24BIT_013.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._14 People Are People_24BIT_014.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._14 People Are People_24BIT_014.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._15 A Question Of Time_24BIT_015.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._15 A Question Of Time_24BIT_015.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._16 Never Let Me Down Again_24BIT_016.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._16 Never Let Me Down Again_24BIT_016.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._17 A Question Of Lust_24BIT_017.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._17 A Question Of Lust_24BIT_017.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._18 Master And Servant_24BIT_018.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._18 Master And Servant_24BIT_018.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._19 Just Can't Get Enough_24BIT_019.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._19 Just Can't Get Enough_24BIT_019.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/._20 Everything Counts_24BIT_020.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/._20 Everything Counts_24BIT_020.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/01 Pimpf_24BIT_001.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/01 Pimpf_24BIT_001.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/02 Behind The Wheel_24BIT_002.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/03 Strangelove_24BIT_003.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/03 Strangelove_24BIT_003.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/04 Sacred_24BIT_004.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/04 Sacred_24BIT_004.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/05 Something To Do_24BIT_005.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/05 Something To Do_24BIT_005.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/06 Blasphemous Rumours_24BIT_006.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/07 Stripped_24BIT_007.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/07 Stripped_24BIT_007.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/08 Somebody_24BIT_008.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/08 Somebody_24BIT_008.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/09 Things You Said_24BIT_009.flac Dec 27 18:44:05 motivo volumio[1005]: info: Using cached record of: music-library/USB/406B-C05E/101/09 Things You Said_24BIT_009.flac Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/10 Black Celebration_24BIT_010.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/10 Black Celebration_24BIT_010.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/11 Shake The Disease_24BIT_011.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/11 Shake The Disease_24BIT_011.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/12 Nothing_24BIT_012.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/12 Nothing_24BIT_012.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/13 Pleasure Little Treasure_24BIT_013.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/13 Pleasure Little Treasure_24BIT_013.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/14 People Are People_24BIT_014.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/14 People Are People_24BIT_014.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/15 A Question Of Time_24BIT_015.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/15 A Question Of Time_24BIT_015.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/16 Never Let Me Down Again_24BIT_016.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/16 Never Let Me Down Again_24BIT_016.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/17 A Question Of Lust_24BIT_017.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/17 A Question Of Lust_24BIT_017.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/18 Master And Servant_24BIT_018.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/18 Master And Servant_24BIT_018.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/19 Just Can't Get Enough_24BIT_019.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/19 Just Can't Get Enough_24BIT_019.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: Adding Item to queue: music-library/USB/406B-C05E/101/20 Everything Counts_24BIT_020.flac Dec 27 18:44:05 motivo volumio[1005]: info: Exploding uri music-library/USB/406B-C05E/101/20 Everything Counts_24BIT_020.flac in service mpd Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::play index undefined Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::startPlaybackTimer Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::clearAddPlayTracks USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F10%20Black%20Celebration_24BIT_010.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/10 Black Celebration_24BIT_010.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F11%20Shake%20The%20Disease_24BIT_011.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/11 Shake The Disease_24BIT_011.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F12%20Nothing_24BIT_012.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/12 Nothing_24BIT_012.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F13%20Pleasure%20Little%20Treasure_24BIT_013.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/13 Pleasure Little Treasure_24BIT_013.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F14%20People%20Are%20People_24BIT_014.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/14 People Are People_24BIT_014.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F15%20A%20Question%20Of%20Time_24BIT_015.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/15 A Question Of Time_24BIT_015.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F16%20Never%20Let%20Me%20Down%20Again_24BIT_016.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/16 Never Let Me Down Again_24BIT_016.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F17%20A%20Question%20Of%20Lust_24BIT_017.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/17 A Question Of Lust_24BIT_017.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F18%20Master%20And%20Servant_24BIT_018.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/18 Master And Servant_24BIT_018.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F19%20Just%20Can't%20Get%20Enough_24BIT_019.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/19 Just Can't Get Enough_24BIT_019.flac Dec 27 18:44:05 motivo volumio[1005]: info: ALBUMART /albumart?cacheid=270&web=//extralarge&path=%2Fmnt%2FUSB%2F406B-C05E%2F101%2F20%20Everything%20Counts_24BIT_020.flac&metadata=false Dec 27 18:44:05 motivo volumio[1005]: info: URI /mnt/USB/406B-C05E/101/20 Everything Counts_24BIT_020.flac Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand stop took 22 milliseconds Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushQueue Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::saveQueue Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::updateTrackBlock Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrackBlock Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:05 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand clear took 19 milliseconds Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand add "USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac" Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:05 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:05 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:05 motivo volumio[1005]: error: updateQueue error: null Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:05 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 5ms Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand add "USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac" took 4 milliseconds Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 3ms Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 2ms Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand play Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:05 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces system playlist update Dec 27 18:44:05 motivo volumio[1005]: info: Ignoring MPD Status Update Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 4ms Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand play took 3 milliseconds Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 2ms Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:44:05 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:44:05 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:44:05 motivo volumio[1005]: info: Dec 27 18:44:05 motivo volumio[1005]: ---------------------------- MPD announces state update: player Dec 27 18:44:05 motivo volumio[1005]: info: ControllerMpd::getState Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand status took 9 milliseconds Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand status took 7 milliseconds Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand status took 14 milliseconds Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand clearerror took 9 milliseconds Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand clearerror took 8 milliseconds Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::parseState Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:44:05 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01 Pimpf_24BIT_001.flac","artist":null,"album":null,"uri":"USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac","trackType":"flac"} Dec 27 18:44:05 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: No code Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01 Pimpf_24BIT_001.flac","artist":null,"album":null,"uri":"USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac","trackType":"flac"} Dec 27 18:44:05 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: No code Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 51ms Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 50ms Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand clearerror took 61 milliseconds Dec 27 18:44:05 motivo volumio[1005]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 27 18:44:05 motivo volumio[1005]: verbose: ControllerMpd::parseTrackInfo Dec 27 18:44:05 motivo volumio[1005]: info: ControllerMpd::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::servicePushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"._01 Pimpf_24BIT_001.flac","artist":null,"album":null,"uri":"USB/406B-C05E/101/._01 Pimpf_24BIT_001.flac","trackType":"flac"} Dec 27 18:44:05 motivo volumio[1005]: verbose: CURRENT POSITION 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::syncState stateService stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: No code Dec 27 18:44:05 motivo volumio[1005]: info: CoreStateMachine::pushState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioPushState Dec 27 18:44:05 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:05 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output update for this device Dec 27 18:44:05 motivo volumio[1005]: info: MRS: Pushing multiroomSync output Dec 27 18:44:05 motivo volumio[1005]: info: ------------------------------ 93ms Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:05 motivo volumio[1005]: info: Updating RAAT Signal Path Dec 27 18:44:07 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:07.991+09:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.94:61216 error="read tcp 192.168.0.97:7331->192.168.0.94:61216: read: connection reset by peer" Dec 27 18:44:07 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:07.991+09:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.94:61216 Dec 27 18:44:07 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:07.992+09:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.94:61216 Dec 27 18:44:08 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 18:44:08 motivo volumio[1005]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 27 18:44:08 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 27 18:44:08 motivo volumio[1005]: info: Received Get System Version Dec 27 18:44:08 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 18:44:08 motivo volumio[1005]: info: Received Get System Info Dec 27 18:44:08 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:44:08 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:44:08 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:44:08 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:08 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:08 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:44:14 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:14.564+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.94:61790 Dec 27 18:44:14 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:44:14 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:44:14 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:44:14 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:14 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:14 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:44:14 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97:3000 from 192.168.0.94 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 27 18:44:14 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 18:44:14 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 18:44:14 motivo sudo[7474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 18:44:14 motivo sudo[7474]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:44:14 motivo sudo[7474]: pam_unix(sudo:session): session closed for user root Dec 27 18:44:14 motivo sudo[7477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 18:44:14 motivo sudo[7477]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:44:14 motivo sudo[7477]: pam_unix(sudo:session): session closed for user root Dec 27 18:44:14 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.94 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 18:44:15 motivo sudo[7480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 18:44:15 motivo sudo[7480]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:44:15 motivo sudo[7480]: pam_unix(sudo:session): session closed for user root Dec 27 18:44:15 motivo sudo[7483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 18:44:15 motivo sudo[7483]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 18:44:15 motivo sudo[7483]: pam_unix(sudo:session): session closed for user root Dec 27 18:44:15 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.94 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:15 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::volumioGetQueue Dec 27 18:44:15 motivo volumio[1005]: info: CoreStateMachine::getQueue Dec 27 18:44:15 motivo volumio[1005]: info: CorePlayQueue::getQueue Dec 27 18:44:15 motivo volumio[1005]: info: Listing playlists Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 18:44:15 motivo volumio[1005]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 27 18:44:15 motivo volumio[1005]: info: Received Get System Info Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:44:15 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:15 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:15 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 18:44:15 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 18:44:16 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 27 18:44:17 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 18:44:17 motivo volumio[1005]: info: Received Get System Info Dec 27 18:44:17 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:44:17 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:44:17 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:44:17 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:17 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:17 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:44:23 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:23.553+09:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.94:61790 error="read tcp 192.168.0.97:7331->192.168.0.94:61790: read: connection reset by peer" Dec 27 18:44:23 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:23.553+09:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.94:61790 Dec 27 18:44:23 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:23.554+09:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.94:61790 Dec 27 18:44:23 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97 from 192.168.0.94 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 18:44:24 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:24.360+09:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.0.94:61340 error="read tcp 192.168.0.97:7331->192.168.0.94:61340: read: connection reset by peer" Dec 27 18:44:24 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:24.360+09:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.94:61340 Dec 27 18:44:24 motivo volumio5-onboarding[1483]: time=2025-12-27T18:44:24.361+09:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.94:61340 Dec 27 18:44:24 motivo ntpd[811]: Soliciting pool server 139.64.134.57 Dec 27 18:44:24 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 18:44:24 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 18:44:24 motivo volumio[1005]: info: Discovery: Getting this device information Dec 27 18:44:24 motivo volumio[1005]: info: CoreCommandRouter::volumioGetState Dec 27 18:44:24 motivo volumio[1005]: info: CorePlayQueue::getTrack 0 Dec 27 18:44:24 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 18:44:25 motivo volumio[1005]: verbose: New Socket.io Connection to 192.168.0.97:3000 from 192.168.0.94 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 27 18:44:25 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 27 18:44:25 motivo volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 27 18:44:25 motivo volumio[1005]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 18:44:25 motivo volumio[1005]: Error: read ECONNRESET Dec 27 18:44:25 motivo volumio[1005]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Dec 27 18:44:25 motivo volumio[1005]: errno: -104, Dec 27 18:44:25 motivo volumio[1005]: code: 'ECONNRESET', Dec 27 18:44:25 motivo volumio[1005]: syscall: 'read' Dec 27 18:44:25 motivo volumio[1005]: } Dec 27 18:44:25 motivo volumio[1005]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 18:44:26 motivo sudo[7512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 18:43 Dec 27 18:44:26 motivo sudo[7512]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 08:12:00 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="fa4db9a5548881d73a1ae68b653901d4"