-- Logs begin at Sun 2024-09-08 19:43:11 CST, end at Sun 2024-09-08 19:48:53 CST. -- Sep 08 19:47:00 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:14 volumio ntpd[894]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 08 19:47:14 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Sep 08 19:47:14 volumio volumio[1005]: info: Saving new wireless network Sep 08 19:47:14 volumio sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Sep 08 19:47:14 volumio sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:14 volumio sudo[2389]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:14 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Sep 08 19:47:14 volumio volumio[1005]: info: Discovery: Restarting Advertising due to device name change Sep 08 19:47:14 volumio sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 08 19:47:14 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:14 volumio systemd[1]: Starting Wireless Services... Sep 08 19:47:15 volumio wireless.js[2395]: Cleaning previous... Sep 08 19:47:15 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Sep 08 19:47:15 volumio dnsmasq[1685]: exiting on receipt of SIGTERM Sep 08 19:47:15 volumio systemd[1]: dnsmasq.service: Succeeded. Sep 08 19:47:15 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Sep 08 19:47:15 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 08 19:47:15 volumio dhcpcd[814]: wlan0: carrier lost Sep 08 19:47:15 volumio dhcpcd[814]: wlan0: deleting route to 169.254.0.0/16 Sep 08 19:47:15 volumio systemd[1]: hostapd.service: Succeeded. Sep 08 19:47:15 volumio dhcpcd[814]: wlan0: deleting default route Sep 08 19:47:15 volumio avahi-daemon[734]: Withdrawing address record for 169.254.160.200 on wlan0. Sep 08 19:47:15 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 08 19:47:15 volumio sudo[2431]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 08 19:47:15 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:15 volumio avahi-daemon[734]: Withdrawing address record for 192.168.211.1 on wlan0. Sep 08 19:47:15 volumio avahi-daemon[734]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Sep 08 19:47:15 volumio volumio[1005]: info: Discovery: A device disappeared from network Sep 08 19:47:15 volumio volumio[1005]: info: Discovery: Device volumio disappeared from network Sep 08 19:47:15 volumio avahi-daemon[734]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 08 19:47:15 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:15 volumio sudo[2437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 08 19:47:15 volumio sudo[2437]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:15 volumio sudo[2437]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:15 volumio wireless.js[2395]: Stopped aP Sep 08 19:47:15 volumio sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 08 19:47:15 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:16 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:16 volumio sudo[2446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 08 19:47:16 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:16 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:16 volumio sudo[2453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 08 19:47:16 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:16 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:16 volumio sudo[2455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 08 19:47:16 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:17 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:17 volumio wireless.js[2395]: SETTING APPROPRIATE REG DOMAIN: CN Sep 08 19:47:17 volumio sudo[2460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 08 19:47:17 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:17 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:17 volumio sudo[2462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Sep 08 19:47:17 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:17 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:17 volumio wireless.js[2395]: SUCCESSFULLY SET NEW REGDOMAIN: CN Sep 08 19:47:17 volumio wireless.js[2395]: Start wireless flow Sep 08 19:47:17 volumio wireless.js[2395]: Stopped hotspot (if there).. Sep 08 19:47:17 volumio sudo[2467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 08 19:47:17 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:17 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:17 volumio sudo[2469]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 08 19:47:17 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:17 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:17 volumio wireless.js[2395]: DHCP IP Sep 08 19:47:17 volumio wpa_supplicant[2471]: Successfully initialized wpa_supplicant Sep 08 19:47:17 volumio wireless.js[2395]: Start ap Sep 08 19:47:17 volumio sudo[2472]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 08 19:47:17 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:17 volumio dhcpcd[2473]: sending commands to master dhcpcd process Sep 08 19:47:17 volumio dhcpcd[814]: control command: /sbin/dhcpcd Sep 08 19:47:17 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:18 volumio wireless.js[2395]: trying... Sep 08 19:47:18 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 08 19:47:18 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:18 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:18 volumio wpa_supplicant[2474]: wlan0: SME: Trying to authenticate with 42:2c:fe:fe:8b:4a (SSID='HUAWEI-91BF1U' freq=2437 MHz) Sep 08 19:47:18 volumio kernel: wlan0: authenticate with 42:2c:fe:fe:8b:4a Sep 08 19:47:18 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Sep 08 19:47:18 volumio kernel: wlan0: send auth to 42:2c:fe:fe:8b:4a (try 1/3) Sep 08 19:47:18 volumio wpa_supplicant[2474]: wlan0: Trying to associate with 42:2c:fe:fe:8b:4a (SSID='HUAWEI-91BF1U' freq=2437 MHz) Sep 08 19:47:18 volumio kernel: wlan0: authenticated Sep 08 19:47:18 volumio kernel: wlan0: associate with 42:2c:fe:fe:8b:4a (try 1/3) Sep 08 19:47:18 volumio kernel: wlan0: RX AssocResp from 42:2c:fe:fe:8b:4a (capab=0x1431 status=0 aid=4) Sep 08 19:47:18 volumio kernel: wlan0: associated Sep 08 19:47:18 volumio wpa_supplicant[2474]: wlan0: Associated with 42:2c:fe:fe:8b:4a Sep 08 19:47:18 volumio wpa_supplicant[2474]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 08 19:47:18 volumio wpa_supplicant[2474]: wlan0: WPA: Key negotiation completed with 42:2c:fe:fe:8b:4a [PTK=CCMP GTK=CCMP] Sep 08 19:47:18 volumio wpa_supplicant[2474]: wlan0: CTRL-EVENT-CONNECTED - Connection to 42:2c:fe:fe:8b:4a completed [id=0 id_str=] Sep 08 19:47:18 volumio dhcpcd[814]: wlan0: carrier acquired Sep 08 19:47:18 volumio dhcpcd[814]: wlan0: IAID ca:59:90:48 Sep 08 19:47:18 volumio dhcpcd[814]: wlan0: soliciting a DHCP lease Sep 08 19:47:18 volumio dhcpcd[814]: wlan0: offered 192.168.3.43 from 192.168.3.1 Sep 08 19:47:18 volumio dhcpcd[814]: wlan0: probing address 192.168.3.43/24 Sep 08 19:47:18 volumio dhcpcd[814]: wlan0: soliciting an IPv6 router Sep 08 19:47:19 volumio wireless.js[2395]: trying... Sep 08 19:47:19 volumio sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 08 19:47:19 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:19 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:19 volumio ntpd[894]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=146 secs Sep 08 19:47:19 volumio ntpd[894]: Deleting interface #5 wlan0, 169.254.160.200#123, interface stats: received=0, sent=0, dropped=0, active_time=118 secs Sep 08 19:47:19 volumio ntpd[894]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 08 19:47:19 volumio ntpd[894]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 08 19:47:19 volumio ntpd[894]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 08 19:47:19 volumio ntpd[894]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 08 19:47:19 volumio volumio[1005]: info: Discovery: Started advertising with name: Volumio Sep 08 19:47:20 volumio wireless.js[2395]: trying... Sep 08 19:47:20 volumio sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 08 19:47:20 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:20 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:21 volumio wireless.js[2395]: trying... Sep 08 19:47:21 volumio sudo[2487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 08 19:47:21 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:21 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:22 volumio wireless.js[2395]: trying... Sep 08 19:47:22 volumio sudo[2490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 08 19:47:22 volumio sudo[2490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:22 volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:23 volumio wireless.js[2395]: trying... Sep 08 19:47:23 volumio sudo[2493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 08 19:47:23 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:23 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:23 volumio dhcpcd[814]: wlan0: leased 192.168.3.43 for 86400 seconds Sep 08 19:47:23 volumio dhcpcd[814]: wlan0: adding route to 192.168.3.0/24 Sep 08 19:47:23 volumio dhcpcd[814]: wlan0: adding default route via 192.168.3.1 Sep 08 19:47:23 volumio avahi-daemon[734]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.43. Sep 08 19:47:23 volumio dhcpcd[814]: wlan0: dhcp_envoption 213: Operation not supported Sep 08 19:47:23 volumio dhcpcd[814]: wlan0: dhcp_envoption 213: Operation not supported Sep 08 19:47:23 volumio avahi-daemon[734]: New relevant interface wlan0.IPv4 for mDNS. Sep 08 19:47:23 volumio avahi-daemon[734]: Registering new address record for 192.168.3.43 on wlan0.IPv4. Sep 08 19:47:23 volumio systemd[1]: Stopping Network Time Service... Sep 08 19:47:23 volumio ntpd[894]: ntpd exiting on signal 15 (Terminated) Sep 08 19:47:23 volumio systemd[1]: ntp.service: Succeeded. Sep 08 19:47:23 volumio systemd[1]: Stopped Network Time Service. Sep 08 19:47:23 volumio systemd[1]: Starting Network Time Service... Sep 08 19:47:23 volumio ntpd[2528]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 08 19:47:23 volumio ntpd[2528]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 08 19:47:23 volumio systemd[1]: Started Network Time Service. Sep 08 19:47:23 volumio ntpd[2534]: proto: precision = 0.255 usec (-22) Sep 08 19:47:23 volumio ntpd[2534]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 08 19:47:23 volumio ntpd[2534]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 08 19:47:23 volumio ntpd[2534]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 621 days ago Sep 08 19:47:23 volumio ntpd[2534]: Listen and drop on 0 v6wildcard [::]:123 Sep 08 19:47:23 volumio ntpd[2534]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 08 19:47:23 volumio ntpd[2534]: Listen normally on 2 lo 127.0.0.1:123 Sep 08 19:47:23 volumio ntpd[2534]: Listen normally on 3 wlan0 192.168.3.43:123 Sep 08 19:47:23 volumio ntpd[2534]: Listening on routing socket on fd #20 for interface updates Sep 08 19:47:23 volumio ntpd[2534]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 08 19:47:23 volumio ntpd[2534]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 08 19:47:24 volumio wireless.js[2395]: trying... Sep 08 19:47:24 volumio sudo[2537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 08 19:47:24 volumio sudo[2537]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:24 volumio sudo[2537]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:24 volumio wireless.js[2395]: Connected to: ----HUAWEI-91BF1U Sep 08 19:47:24 volumio wireless.js[2395]: ---- Sep 08 19:47:24 volumio sudo[2540]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 19:47:24 volumio sudo[2540]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:24 volumio sudo[2540]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:24 volumio wireless.js[2395]: ... joined AP, wlan0 IPv4 is 192.168.3.43, ipV6 is undefined Sep 08 19:47:24 volumio wireless.js[2395]: It's done! AP Sep 08 19:47:24 volumio iwconfig[2544]: Error for wireless request "Set Power Management" (8B2C) : Sep 08 19:47:24 volumio iwconfig[2544]: SET failed on device wlan0 ; Operation not supported. Sep 08 19:47:24 volumio systemd[1]: Started Wireless Services. Sep 08 19:47:24 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:24 volumio volumio[1005]: info: Discovery: adding 540a08a4-e137-465d-a95e-8505d8014e38 Sep 08 19:47:24 volumio volumio[1005]: info: Discovery: Found device Volumio Sep 08 19:47:24 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:47:24 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:24 volumio ntpd[2534]: Soliciting pool server 84.16.67.12 Sep 08 19:47:25 volumio ntpd[2534]: Soliciting pool server 116.203.151.74 Sep 08 19:47:26 volumio ntpd[2534]: Soliciting pool server 162.159.200.1 Sep 08 19:47:26 volumio ntpd[2534]: Soliciting pool server 162.159.200.123 Sep 08 19:47:26 volumio ntpd[2534]: Soliciting pool server 108.59.2.24 Sep 08 19:47:26 volumio ntpd[2534]: Soliciting pool server 202.118.1.81 Sep 08 19:47:27 volumio ntpd[2534]: Soliciting pool server 193.182.111.12 Sep 08 19:47:27 volumio ntpd[2534]: Soliciting pool server 193.182.111.141 Sep 08 19:47:27 volumio ntpd[2534]: Soliciting pool server 78.46.102.180 Sep 08 19:47:28 volumio ntpd[2534]: Soliciting pool server 193.182.111.14 Sep 08 19:47:28 volumio ntpd[2534]: Soliciting pool server 110.42.98.138 Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Sep 08 19:47:29 volumio sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 19:47:29 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:29 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:29 volumio sudo[2560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 08 19:47:29 volumio sudo[2560]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 19:47:29 volumio sudo[2560]: pam_unix(sudo:session): session closed for user root Sep 08 19:47:29 volumio ntpd[2534]: Soliciting pool server 84.16.73.33 Sep 08 19:47:29 volumio ntpd[2534]: Soliciting pool server 2001:41d0:305:2100::3f3e Sep 08 19:47:30 volumio kernel: usb 8-1: USB disconnect, device number 2 Sep 08 19:47:30 volumio thd[739]: Error reading device '/dev/input/event2' Sep 08 19:47:30 volumio kernel: usb 8-1: new low-speed USB device number 3 using xhci_hcd Sep 08 19:47:30 volumio kernel: usb 8-1: New USB device found, idVendor=046d, idProduct=c077, bcdDevice=72.00 Sep 08 19:47:30 volumio kernel: usb 8-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 08 19:47:30 volumio kernel: usb 8-1: Product: USB Optical Mouse Sep 08 19:47:30 volumio kernel: usb 8-1: Manufacturer: Logitech Sep 08 19:47:30 volumio kernel: input: Logitech USB Optical Mouse as /devices/pci0000:00/0000:00:04.0/0000:01:00.0/usb8/8-1/8-1:1.0/0003:046D:C077.0002/input/input10 Sep 08 19:47:30 volumio kernel: hid-generic 0003:046D:C077.0002: input,hidraw0: USB HID v1.11 Mouse [Logitech USB Optical Mouse] on usb-0000:01:00.0-1/input0 Sep 08 19:47:30 volumio systemd-udevd[2565]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 08 19:47:30 volumio systemd-udevd[2566]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 08 19:47:30 volumio kernel: usb 9-2: new SuperSpeed USB device number 2 using xhci_hcd Sep 08 19:47:30 volumio kernel: usb 9-2: New USB device found, idVendor=0bc2, idProduct=231b, bcdDevice=18.08 Sep 08 19:47:30 volumio kernel: usb 9-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 08 19:47:30 volumio kernel: usb 9-2: Product: Expansion Sep 08 19:47:30 volumio kernel: usb 9-2: Manufacturer: Seagate Sep 08 19:47:30 volumio kernel: usb 9-2: SerialNumber: NA88NBWV Sep 08 19:47:30 volumio kernel: scsi host6: uas Sep 08 19:47:30 volumio kernel: scsi 6:0:0:0: Direct-Access Seagate Expansion 1808 PQ: 0 ANSI: 6 Sep 08 19:47:30 volumio kernel: sd 6:0:0:0: Attached scsi generic sg1 type 0 Sep 08 19:47:31 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 19:47:31 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 19:47:31 volumio volumio[1005]: info: Discovery: Getting this device information Sep 08 19:47:31 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:47:31 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:31 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 19:47:31 volumio volumio[1005]: verbose: New Socket.io Connection to 192.168.3.43 from 192.168.3.18 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Sep 08 19:47:31 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:47:31 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:31 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 19:47:31 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] 1953525167 512-byte logical blocks: (1.00 TB/932 GiB) Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] 4096-byte physical blocks Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] Write Protect is off Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] Mode Sense: 53 00 00 08 Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Sep 08 19:47:33 volumio kernel: sdb: sdb1 Sep 08 19:47:33 volumio kernel: sd 6:0:0:0: [sdb] Attached SCSI disk Sep 08 19:47:08 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 19:47:08 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:47:09 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 19:47:09 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 19:47:09 volumio volumio[1005]: info: Discovery: Getting this device information Sep 08 19:47:09 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:47:09 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 19:47:12 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 19:47:12 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:47:29 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::ClearQueue Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::stop Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::stPlaybackTimer Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::updateTrackBlock Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrackBlock Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::serviceStop Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::serviceStop Sep 08 19:47:29 volumio volumio[1005]: info: [1725796049046] ControllerWebradio::stop Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::clearPlayQueue Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::saveQueue Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushQueue Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::addQueueItems Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::addQueueItems Sep 08 19:47:29 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:47:29 volumio volumio[1005]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1454276 Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushQueue Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::saveQueue Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::updateTrackBlock Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrackBlock Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPlay Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::play index 0 Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::stop Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::play index undefined Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::startPlaybackTimer Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:29 volumio volumio[1005]: info: [1725796049097] ControllerWebradio::clearAddPlayTrack Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 19:47:29 volumio volumio[1005]: info: Sep 08 19:47:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand stop took 57 milliseconds Sep 08 19:47:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand stop took 7 milliseconds Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 19:47:29 volumio volumio[1005]: info: Sep 08 19:47:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:47:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:47:29 volumio volumio[1005]: info: Sep 08 19:47:29 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:47:29 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand status took 5 milliseconds Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand clear took 4 milliseconds Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand status took 2 milliseconds Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1454276" Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:47:29 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 19:47:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:47:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:47:29 volumio volumio[1005]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 08 19:47:29 volumio volumio[1005]: info: ------------------------------ 30ms Sep 08 19:47:29 volumio volumio[1005]: info: Sep 08 19:47:29 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:47:29 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:47:29 volumio volumio[1005]: error: updateQueue error: null Sep 08 19:47:29 volumio volumio[1005]: info: ------------------------------ 31ms Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 30 milliseconds Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:47:29 volumio volumio[1005]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 08 19:47:29 volumio volumio[1005]: info: ------------------------------ 40ms Sep 08 19:47:29 volumio volumio[1005]: error: updateQueue error: null Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1454276" took 538 milliseconds Sep 08 19:47:29 volumio volumio[1005]: info: ------------------------------ 514ms Sep 08 19:47:29 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 19:47:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand play Sep 08 19:47:29 volumio volumio[1005]: info: sendMpdCommand play took 2 milliseconds Sep 08 19:47:56 volumio volumio[1005]: verbose: New Socket.io Connection to 192.168.3.43 from 192.168.3.18 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 Sep 08 19:47:56 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:47:56 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 19:47:56 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 19:47:56 volumio volumio[1005]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ANTENNE%20BAYERN/5c0c54af-2e05-45c8-97fe-a96c0067c656.jpg' Sep 08 19:47:58 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 19:47:58 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 19:47:58 volumio volumio[1005]: info: Discovery: Getting this device information Sep 08 19:47:58 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:47:58 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 19:48:01 volumio volumio[1005]: verbose: New Socket.io Connection to 192.168.3.43 from 192.168.3.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Sep 08 19:48:01 volumio volumio[1005]: verbose: New Socket.io Connection to 192.168.3.43 from 192.168.3.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 Sep 08 19:48:01 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:48:01 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 08 19:48:01 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 08 19:48:03 volumio volumio[1005]: verbose: New Socket.io Connection to 192.168.3.43 from 192.168.3.44 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 19:48:03 volumio volumio[1005]: info: Received Get System Info Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 19:48:03 volumio volumio[1005]: info: Discovery: Getting this device information Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::volumioGetState Sep 08 19:48:03 volumio volumio[1005]: info: Listing playlists Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::volumioGetQueue Sep 08 19:48:03 volumio volumio[1005]: info: CoreStateMachine::getQueue Sep 08 19:48:03 volumio volumio[1005]: info: CorePlayQueue::getQueue Sep 08 19:48:03 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 08 19:48:19 volumio ntpd[2534]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 08 19:48:22 volumio nmbd[873]: [2024/09/08 19:48:22.118161, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 08 19:48:22 volumio nmbd[873]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Sep 08 19:48:25 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:48:25 volumio volumio[1005]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::ClearQueue Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::stop Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:48:25 volumio volumio[1005]: info: CorePlayQueue::clearPlayQueue Sep 08 19:48:25 volumio volumio[1005]: info: CorePlayQueue::saveQueue Sep 08 19:48:25 volumio volumio[1005]: info: CoreCommandRouter::volumioPushQueue Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::addQueueItems Sep 08 19:48:25 volumio volumio[1005]: info: CorePlayQueue::addQueueItems Sep 08 19:48:25 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:48:25 volumio volumio[1005]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Sep 08 19:48:25 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 08 19:48:25 volumio volumio[1005]: info: CoreCommandRouter::volumioPushQueue Sep 08 19:48:25 volumio volumio[1005]: info: CorePlayQueue::saveQueue Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::updateTrackBlock Sep 08 19:48:25 volumio volumio[1005]: info: CorePlayQueue::getTrackBlock Sep 08 19:48:25 volumio volumio[1005]: info: CoreCommandRouter::volumioPlay Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::play index 0 Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::stop Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::play index undefined Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 19:48:25 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:25 volumio volumio[1005]: info: CoreStateMachine::startPlaybackTimer Sep 08 19:48:25 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:25 volumio volumio[1005]: info: [1725796105344] ControllerWebradio::clearAddPlayTrack Sep 08 19:48:25 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 19:48:25 volumio volumio[1005]: info: sendMpdCommand stop took 3 milliseconds Sep 08 19:48:25 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 19:48:25 volumio volumio[1005]: info: Sep 08 19:48:25 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:25 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:25 volumio volumio[1005]: info: sendMpdCommand clear took 2 milliseconds Sep 08 19:48:25 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Sep 08 19:48:25 volumio volumio[1005]: info: Sep 08 19:48:25 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:25 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:25 volumio volumio[1005]: error: updateQueue error: null Sep 08 19:48:25 volumio volumio[1005]: info: ------------------------------ 7ms Sep 08 19:48:26 volumio volumio[1005]: info: Sep 08 19:48:26 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:26 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:26 volumio volumio[1005]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 1402 milliseconds Sep 08 19:48:26 volumio volumio[1005]: info: ------------------------------ 1402ms Sep 08 19:48:26 volumio volumio[1005]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 19:48:26 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand play Sep 08 19:48:26 volumio volumio[1005]: info: Sep 08 19:48:26 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:26 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:26 volumio volumio[1005]: info: ------------------------------ 10ms Sep 08 19:48:26 volumio volumio[1005]: info: sendMpdCommand play took 6 milliseconds Sep 08 19:48:26 volumio volumio[1005]: info: ------------------------------ 6ms Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 12 milliseconds Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 11 milliseconds Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 10568/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:29 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus stop Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 22ms Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 9 milliseconds Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 2 milliseconds Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 10568/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:29 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:29 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 69ms Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 46 milliseconds Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 45 milliseconds Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 10568/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:29 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:29 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 10568/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:29 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:29 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 135ms Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 133ms Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:29 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:29 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:29 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 16ms Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 13 milliseconds Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:29 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:29 volumio volumio[1005]: info: Sep 08 19:48:29 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 31ms Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 30 milliseconds Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 29ms Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 23 milliseconds Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 08 19:48:29 volumio volumio[1005]: info: ------------------------------ 20ms Sep 08 19:48:29 volumio volumio[1005]: info: sendMpdCommand status took 19 milliseconds Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:29 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:29 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:29 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:29 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1091,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:29 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:29 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:29 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 19:48:29 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 97ms Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 70 milliseconds Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 70 milliseconds Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 70 milliseconds Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:30 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:30 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1342,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:30 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:30 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:30 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:30 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1421,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:30 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:30 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:30 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:30 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:30 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:30 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 289ms Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 283ms Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 283ms Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:30 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:30 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 12ms Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand status took 11 milliseconds Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 9ms Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand status took 6 milliseconds Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:30 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:30 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:30 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:30 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:30 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:30 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:30 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:30 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 92ms Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 89ms Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:30 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces system playlist update Sep 08 19:48:30 volumio volumio[1005]: info: Ignoring MPD Status Update Sep 08 19:48:30 volumio volumio[1005]: info: Sep 08 19:48:30 volumio volumio[1005]: ---------------------------- MPD announces state update: player Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::getState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand status Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 12ms Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand status took 12 milliseconds Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 11ms Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand status took 6 milliseconds Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseState Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 19:48:30 volumio volumio[1005]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:30 volumio volumio[1005]: verbose: ControllerMpd::parseTrackInfo Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:30 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:30 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:30 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:30 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ControllerMpd::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::servicePushState Sep 08 19:48:30 volumio volumio[1005]: info: CorePlayQueue::getTrack 0 Sep 08 19:48:30 volumio volumio[1005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue - U Make Me Wanna","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Sep 08 19:48:30 volumio volumio[1005]: verbose: CURRENT POSITION 0 Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState stateService play Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::syncState currentStatus play Sep 08 19:48:30 volumio volumio[1005]: info: Received an update from plugin. extracting info from payload Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreStateMachine::pushState Sep 08 19:48:30 volumio volumio[1005]: info: CoreCommandRouter::volumioPushState Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 113ms Sep 08 19:48:30 volumio volumio[1005]: info: ------------------------------ 112ms Sep 08 19:48:36 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 19:48:36 volumio volumio[1005]: info: CURURI: music-library Sep 08 19:48:36 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:48:37 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 19:48:37 volumio volumio[1005]: info: CURURI: music-library/USB Sep 08 19:48:37 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:48:38 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 19:48:38 volumio volumio[1005]: info: CURURI: music-library/USB/Seagate_Expansion_Drive Sep 08 19:48:38 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:48:51 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 19:48:51 volumio volumio[1005]: info: CURURI: music-library/USB Sep 08 19:48:51 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:48:52 volumio volumio[1005]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 19:48:52 volumio volumio[1005]: info: CURURI: music-library/USB/Seagate_Expansion_Drive Sep 08 19:48:52 volumio volumio[1005]: info: Preload queue cleared Sep 08 19:48:53 volumio volumio[1005]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 19:48:53 volumio volumio[1005]: Error: connect ETIMEDOUT 108.160.170.26:80 Sep 08 19:48:53 volumio volumio[1005]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 08 19:48:53 volumio volumio[1005]: errno: -110, Sep 08 19:48:53 volumio volumio[1005]: code: 'ETIMEDOUT', Sep 08 19:48:53 volumio volumio[1005]: syscall: 'connect', Sep 08 19:48:53 volumio volumio[1005]: address: '108.160.170.26', Sep 08 19:48:53 volumio volumio[1005]: port: 80 Sep 08 19:48:53 volumio volumio[1005]: } Sep 08 19:48:53 volumio volumio[1005]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 19:48:53 volumio sudo[3024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 19:47 Sep 08 19:48:53 volumio sudo[3024]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"