-- Logs begin at Thu 2024-04-04 22:31:51 UTC, end at Thu 2024-04-04 22:33:51 UTC. --
Apr 04 22:32:00 volumio volumio-remote-updater[548]: Error: No active session
Apr 04 22:32:00 volumio volumio-remote-updater[548]: [2024-04-04 22:32:00] [info] asio async_connect error: system:111 (Connection refused)
Apr 04 22:32:00 volumio volumio-remote-updater[548]: [2024-04-04 22:32:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 04 22:32:00 volumio volumio-remote-updater[548]: [2024-04-04 22:32:00] [error] handle_connect error: Underlying Transport Error
Apr 04 22:32:00 volumio volumio[547]: Could not open config: /tmp/upmpdcli.conf
Apr 04 22:32:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 04 22:32:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 04 22:32:00 volumio systemd[1]: systemd-rfkill.service: Succeeded.
Apr 04 22:32:00 volumio ntpd[701]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Apr 04 22:32:01 volumio sudo[544]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_progressweb.service: Main process exited, code=exited, status=1/FAILURE
Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_progressweb.service: Failed with result 'exit-code'.
Apr 04 22:32:01 volumio nmbd[679]: [2024/04/04 22:32:01.269805, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
Apr 04 22:32:01 volumio nmbd[679]: started asyncdns process 722
Apr 04 22:32:01 volumio nmbd[679]: [2024/04/04 22:32:01.271553, 0] ../lib/util/become_daemon.c:149(daemon_status)
Apr 04 22:32:01 volumio nmbd[679]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
Apr 04 22:32:01 volumio nmbd[679]: [2024/04/04 22:32:01.271686, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
Apr 04 22:32:01 volumio nmbd[679]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
Apr 04 22:32:01 volumio sudo[557]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_web.service: Main process exited, code=exited, status=1/FAILURE
Apr 04 22:32:01 volumio systemd[1]: nanosoundcd_web.service: Failed with result 'exit-code'.
Apr 04 22:32:01 volumio sh[445]: eth0: carrier acquired
Apr 04 22:32:01 volumio dhcpcd[496]: eth0: carrier acquired
Apr 04 22:32:01 volumio sh[445]: DUID 00:01:00:01:29:d8:61:9e:dc:a6:32:22:db:74
Apr 04 22:32:01 volumio sh[445]: eth0: IAID 32:75:1b:7f
Apr 04 22:32:01 volumio dhcpcd[496]: DUID 00:01:00:01:29:d8:61:9e:dc:a6:32:22:db:74
Apr 04 22:32:01 volumio dhcpcd[496]: eth0: IAID 32:75:1b:7f
Apr 04 22:32:01 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
Apr 04 22:32:01 volumio dhcpcd[496]: eth0: adding address fe80::447d:a1a5:c470:d23f
Apr 04 22:32:01 volumio sh[445]: eth0: adding address fe80::447d:a1a5:c470:d23f
Apr 04 22:32:01 volumio sh[445]: ipv6_addaddr1: Permission denied
Apr 04 22:32:01 volumio dhcpcd[496]: ipv6_addaddr1: Permission denied
Apr 04 22:32:01 volumio ifplugd(eth0)[694]: Link beat detected.
Apr 04 22:32:01 volumio ifplugd(eth0)[694]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Apr 04 22:32:01 volumio ifplugd(eth0)[694]: client: ifup: waiting for lock on /run/network/ifstate.eth0
Apr 04 22:32:01 volumio ntpd[701]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Apr 04 22:32:02 volumio wireless.js[540]: WIRELESS DAEMON: start
Apr 04 22:32:02 volumio wireless.js[540]: WIRELESS: Loaded configuration
Apr 04 22:32:02 volumio wireless.js[540]: Cleaning previous...
Apr 04 22:32:02 volumio sudo[733]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 04 22:32:02 volumio sudo[733]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:02 volumio sudo[733]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:02 volumio sudo[735]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 04 22:32:02 volumio sudo[735]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:02 volumio sh[445]: eth0: soliciting a DHCP lease
Apr 04 22:32:02 volumio dhcpcd[496]: eth0: soliciting a DHCP lease
Apr 04 22:32:02 volumio sh[445]: eth0: soliciting an IPv6 router
Apr 04 22:32:02 volumio dhcpcd[496]: eth0: soliciting an IPv6 router
Apr 04 22:32:02 volumio mpd[697]: Apr 04 22:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 04 22:32:02 volumio mpd[697]: Apr 04 22:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Apr 04 22:32:02 volumio mpd[697]: Apr 04 22:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Apr 04 22:32:02 volumio sudo[735]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:02 volumio systemd[1]: Started Music Player Daemon.
Apr 04 22:32:02 volumio wireless.js[540]: Stopped aP
Apr 04 22:32:02 volumio wireless.js[540]: Start wireless flow
Apr 04 22:32:02 volumio wireless.js[540]: Stopped hotspot (if there)..
Apr 04 22:32:02 volumio sudo[744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Apr 04 22:32:02 volumio sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:02 volumio sudo[744]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:02 volumio sudo[746]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Apr 04 22:32:02 volumio sudo[746]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:02 volumio sudo[746]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:02 volumio wireless.js[540]: DHCP IP
Apr 04 22:32:02 volumio wireless.js[540]: Start ap
Apr 04 22:32:02 volumio wpa_supplicant[748]: Successfully initialized wpa_supplicant
Apr 04 22:32:02 volumio sudo[749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Apr 04 22:32:02 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Apr 04 22:32:02 volumio sudo[749]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:02 volumio dhcpcd[750]: dev: loaded udev
Apr 04 22:32:02 volumio ntpd[701]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Apr 04 22:32:02 volumio dhcpcd-run-hooks[759]: wlan0: starting wpa_supplicant
Apr 04 22:32:03 volumio dhcpcd-run-hooks[764]: wlan0: failed to start wpa_supplicant
Apr 04 22:32:03 volumio dhcpcd-run-hooks[765]: wlan0: Successfully initialized wpa_supplicant
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Ma
Apr 04 22:32:03 volumio dhcpcd[750]: wlan0: connected to Access Point `'
Apr 04 22:32:03 volumio dhcpcd[750]: DUID 00:01:00:01:29:d8:61:9e:dc:a6:32:22:db:74
Apr 04 22:32:03 volumio dhcpcd[750]: eth0: IAID 32:75:1b:7f
Apr 04 22:32:03 volumio dhcpcd[750]: eth0: adding address fe80::447d:a1a5:c470:d23f
Apr 04 22:32:03 volumio dhcpcd[750]: ipv6_addaddr1: Permission denied
Apr 04 22:32:03 volumio dhcpcd[750]: wlan0: waiting for carrier
Apr 04 22:32:03 volumio dhcpcd[750]: eth0: soliciting an IPv6 router
Apr 04 22:32:03 volumio dhcpcd[750]: eth0: soliciting a DHCP lease
Apr 04 22:32:03 volumio sh[445]: eth0: offered 192.168.2.141 from 192.168.2.1
Apr 04 22:32:03 volumio dhcpcd[496]: eth0: offered 192.168.2.141 from 192.168.2.1
Apr 04 22:32:03 volumio sh[445]: eth0: probing address 192.168.2.141/24
Apr 04 22:32:03 volumio dhcpcd[496]: eth0: probing address 192.168.2.141/24
Apr 04 22:32:03 volumio wireless.js[540]: trying...
Apr 04 22:32:03 volumio sudo[776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:03 volumio sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:03 volumio sudo[776]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:03 volumio ntpd[701]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Apr 04 22:32:04 volumio kernel: Bluetooth: Core ver 2.22
Apr 04 22:32:04 volumio kernel: NET: Registered protocol family 31
Apr 04 22:32:04 volumio kernel: Bluetooth: HCI device and connection manager initialized
Apr 04 22:32:04 volumio kernel: Bluetooth: HCI socket layer initialized
Apr 04 22:32:04 volumio kernel: Bluetooth: L2CAP socket layer initialized
Apr 04 22:32:04 volumio kernel: Bluetooth: SCO socket layer initialized
Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART driver ver 2.3
Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART protocol H4 registered
Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
Apr 04 22:32:04 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered
Apr 04 22:32:04 volumio btuart[523]: bcm43xx_init
Apr 04 22:32:04 volumio btuart[523]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd
Apr 04 22:32:04 volumio btuart[523]: Set Controller UART speed to 3000000 bit/s
Apr 04 22:32:04 volumio btuart[523]: Device setup complete
Apr 04 22:32:04 volumio systemd[1]: Starting Load/Save RF Kill Switch Status...
Apr 04 22:32:04 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART.
Apr 04 22:32:04 volumio systemd[1]: Started Load/Save RF Kill Switch Status.
Apr 04 22:32:04 volumio systemd[1]: Created slice system-bthelper.slice.
Apr 04 22:32:04 volumio systemd[1]: Starting Raspberry Pi bluetooth helper...
Apr 04 22:32:04 volumio bthelper[787]: Raspberry Pi BDADDR already set
Apr 04 22:32:04 volumio systemd[1]: Started Raspberry Pi bluetooth helper.
Apr 04 22:32:04 volumio systemd[1]: Starting Bluetooth service...
Apr 04 22:32:04 volumio bluetoothd[797]: Bluetooth daemon 5.50
Apr 04 22:32:04 volumio bluetoothd[797]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Apr 04 22:32:04 volumio systemd[1]: Started Bluetooth service.
Apr 04 22:32:04 volumio systemd[1]: Reached target Bluetooth.
Apr 04 22:32:04 volumio bluetoothd[797]: Starting SDP server
Apr 04 22:32:04 volumio bluetoothd[797]: Excluding (cli) sap
Apr 04 22:32:04 volumio bluetoothd[797]: Bluetooth management interface 1.18 initialized
Apr 04 22:32:04 volumio dbus-daemon[519]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=797 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Apr 04 22:32:04 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Apr 04 22:32:04 volumio kernel: Bluetooth: BNEP filters: protocol multicast
Apr 04 22:32:04 volumio kernel: Bluetooth: BNEP socket layer initialized
Apr 04 22:32:04 volumio systemd[1]: Starting Hostname Service...
Apr 04 22:32:04 volumio dbus-daemon[519]: [system] Successfully activated service 'org.freedesktop.hostname1'
Apr 04 22:32:04 volumio systemd[1]: Started Hostname Service.
Apr 04 22:32:04 volumio bluetoothd[797]: Failed to set privacy: Rejected (0x0b)
Apr 04 22:32:04 volumio wireless.js[540]: trying...
Apr 04 22:32:04 volumio sudo[801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:04 volumio sudo[801]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:04 volumio sudo[801]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:05 volumio volumio-remote-updater[548]: [2024-04-04 22:32:05] [info] asio async_connect error: system:111 (Connection refused)
Apr 04 22:32:05 volumio volumio-remote-updater[548]: [2024-04-04 22:32:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 04 22:32:05 volumio volumio-remote-updater[548]: [2024-04-04 22:32:05] [error] handle_connect error: Underlying Transport Error
Apr 04 22:32:05 volumio wpa_supplicant[763]: wlan0: Trying to associate with SSID 'EasyBox-2D2404'
Apr 04 22:32:05 volumio wireless.js[540]: trying...
Apr 04 22:32:05 volumio sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:05 volumio sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:05 volumio sudo[804]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:06 volumio wireless.js[540]: trying...
Apr 04 22:32:06 volumio sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:06 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:06 volumio sudo[807]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:07 volumio wireless.js[540]: trying...
Apr 04 22:32:07 volumio sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:07 volumio sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:07 volumio sudo[810]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: carrier acquired
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: connected to Access Point `EasyBox-2D2404'
Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: Associated with 3c:98:72:d6:d4:ae
Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:98:72:d6:d4:ae completed [id=0 id_str=]
Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 04 22:32:08 volumio wpa_supplicant[763]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: IAID 32:75:1b:80
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: adding address fe80::5e1a:dc86:ba4e:bdd1
Apr 04 22:32:08 volumio dhcpcd[750]: ipv6_addaddr1: Permission denied
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: carrier lost
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: carrier acquired
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: IAID 32:75:1b:80
Apr 04 22:32:08 volumio dhcpcd[750]: eth0: probing for an IPv4LL address
Apr 04 22:32:08 volumio sh[445]: eth0: leased 192.168.2.141 for 31536000 seconds
Apr 04 22:32:08 volumio sh[445]: eth0: adding route to 192.168.2.0/24
Apr 04 22:32:08 volumio sh[445]: eth0: adding default route via 192.168.2.1
Apr 04 22:32:08 volumio dhcpcd[496]: eth0: leased 192.168.2.141 for 31536000 seconds
Apr 04 22:32:08 volumio avahi-daemon[518]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.141.
Apr 04 22:32:08 volumio avahi-daemon[518]: New relevant interface eth0.IPv4 for mDNS.
Apr 04 22:32:08 volumio dhcpcd[496]: eth0: adding route to 192.168.2.0/24
Apr 04 22:32:08 volumio avahi-daemon[518]: Registering new address record for 192.168.2.141 on eth0.IPv4.
Apr 04 22:32:08 volumio dhcpcd[496]: eth0: adding default route via 192.168.2.1
Apr 04 22:32:08 volumio sh[445]: forked to background, child pid 845
Apr 04 22:32:08 volumio dhcpcd[496]: forked to background, child pid 845
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: rebinding lease of 192.168.2.127
Apr 04 22:32:08 volumio wireless.js[540]: trying...
Apr 04 22:32:08 volumio sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:08 volumio sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:08 volumio sudo[847]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:08 volumio dhcpcd[750]: wlan0: soliciting an IPv6 router
Apr 04 22:32:08 volumio ifplugd(eth0)[694]: client: ifup: interface eth0 already configured
Apr 04 22:32:08 volumio sh[445]: eth0=eth0
Apr 04 22:32:09 volumio ifplugd(eth0)[694]: Program executed successfully.
Apr 04 22:32:09 volumio systemd[1]: systemd-rfkill.service: Succeeded.
Apr 04 22:32:09 volumio bthelper[787]: Changing power off succeeded
Apr 04 22:32:09 volumio dhcpcd[750]: wlan0: probing address 192.168.2.127/24
Apr 04 22:32:09 volumio dhcpcd[750]: eth0: offered 192.168.2.141 from 192.168.2.1
Apr 04 22:32:09 volumio bthelper[787]: [CHG] Controller DC:A6:32:75:1B:81 Class: 0x0000041c
Apr 04 22:32:09 volumio bthelper[787]: Changing power on succeeded
Apr 04 22:32:09 volumio dhcpcd[750]: eth0: leased 192.168.2.141 for 31536000 seconds
Apr 04 22:32:09 volumio dhcpcd[750]: eth0: adding route to 192.168.2.0/24
Apr 04 22:32:09 volumio dhcpcd[750]: eth0: adding default route via 192.168.2.1
Apr 04 22:32:09 volumio wireless.js[540]: trying...
Apr 04 22:32:09 volumio dhcpcd[750]: forked to background, child pid 904
Apr 04 22:32:09 volumio sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:09 volumio sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:09 volumio sudo[903]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:09 volumio sudo[749]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:10 volumio wireless.js[540]: trying...
Apr 04 22:32:10 volumio sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:10 volumio sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:10 volumio sudo[907]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:10 volumio ntpd[701]: Listen normally on 3 eth0 192.168.2.141:123
Apr 04 22:32:10 volumio ntpd[701]: new interface(s) found: waking up resolver
Apr 04 22:32:11 volumio systemd[1]: Started Samba NMB Daemon.
Apr 04 22:32:11 volumio nmbd[679]: [2024/04/04 22:32:11.280588, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 04 22:32:11 volumio nmbd[679]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Apr 04 22:32:11 volumio systemd[1]: Starting Samba Winbind Daemon...
Apr 04 22:32:11 volumio nmbd[679]: [2024/04/04 22:32:11.296782, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Apr 04 22:32:11 volumio nmbd[679]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.141 for name WORKGROUP<1d>.
Apr 04 22:32:11 volumio nmbd[679]: This response was from IP 192.168.2.100, reporting an IP address of 192.168.2.100.
Apr 04 22:32:11 volumio winbindd[909]: [2024/04/04 22:32:11.466730, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Apr 04 22:32:11 volumio winbindd[909]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Apr 04 22:32:11 volumio winbindd[909]: [2024/04/04 22:32:11.484268, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 04 22:32:11 volumio winbindd[909]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Apr 04 22:32:11 volumio systemd[1]: Started Samba Winbind Daemon.
Apr 04 22:32:11 volumio systemd[1]: Starting Samba SMB Daemon...
Apr 04 22:32:11 volumio winbindd[909]: [2024/04/04 22:32:11.795844, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv)
Apr 04 22:32:11 volumio winbindd[909]: res_names->count = 3, expected 4
Apr 04 22:32:11 volumio wireless.js[540]: trying...
Apr 04 22:32:11 volumio smbd[914]: [2024/04/04 22:32:11.833766, 0] ../lib/util/become_daemon.c:138(daemon_ready)
Apr 04 22:32:11 volumio systemd[1]: Started Samba SMB Daemon.
Apr 04 22:32:11 volumio smbd[914]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Apr 04 22:32:11 volumio sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:11 volumio sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:11 volumio sudo[921]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:12 volumio volumio-remote-updater[548]: [2024-04-04 22:32:12] [info] asio async_connect error: system:111 (Connection refused)
Apr 04 22:32:12 volumio volumio-remote-updater[548]: [2024-04-04 22:32:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Apr 04 22:32:12 volumio volumio-remote-updater[548]: [2024-04-04 22:32:12] [error] handle_connect error: Underlying Transport Error
Apr 04 22:32:12 volumio wireless.js[540]: trying...
Apr 04 22:32:12 volumio sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:12 volumio sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:12 volumio sudo[926]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:13 volumio kernel: cam-dummy-reg: disabling
Apr 04 22:32:13 volumio kernel: cam1-reg: disabling
Apr 04 22:32:13 volumio wireless.js[540]: trying...
Apr 04 22:32:13 volumio sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:13 volumio sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:13 volumio sudo[929]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:14 volumio dhcpcd[904]: eth0: using IPv4LL address 169.254.197.80
Apr 04 22:32:14 volumio dhcpcd[904]: eth0: adding route to 169.254.0.0/16
Apr 04 22:32:14 volumio avahi-daemon[518]: Registering new address record for 169.254.197.80 on eth0.IPv4.
Apr 04 22:32:14 volumio dhcpcd[904]: wlan0: leased 192.168.2.127 for 31536000 seconds
Apr 04 22:32:14 volumio avahi-daemon[518]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.127.
Apr 04 22:32:14 volumio avahi-daemon[518]: New relevant interface wlan0.IPv4 for mDNS.
Apr 04 22:32:14 volumio dhcpcd[904]: wlan0: adding route to 192.168.2.0/24
Apr 04 22:32:14 volumio avahi-daemon[518]: Registering new address record for 192.168.2.127 on wlan0.IPv4.
Apr 04 22:32:14 volumio dhcpcd[904]: wlan0: adding default route via 192.168.2.1
Apr 04 22:32:14 volumio wireless.js[540]: trying...
Apr 04 22:32:14 volumio sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Apr 04 22:32:14 volumio sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:14 volumio sudo[972]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:14 volumio wireless.js[540]: Connected to: ----EasyBox-2D2404
Apr 04 22:32:14 volumio wireless.js[540]: ----
Apr 04 22:32:14 volumio sudo[975]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 04 22:32:14 volumio sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:14 volumio sudo[975]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:14 volumio wireless.js[540]: ... joined AP, wlan0 IPv4 is 192.168.2.127, ipV6 is undefined
Apr 04 22:32:14 volumio wireless.js[540]: It's done! AP
Apr 04 22:32:14 volumio systemd[1]: Started Wireless Services.
Apr 04 22:32:14 volumio systemd[1]: Started Volumio Backend Module.
Apr 04 22:32:14 volumio systemd[1]: Started Volumio Streaming Daemon.
Apr 04 22:32:14 volumio systemd[1]: Reached target Multi-User System.
Apr 04 22:32:14 volumio systemd[1]: Reached target Graphical Interface.
Apr 04 22:32:14 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Apr 04 22:32:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Apr 04 22:32:14 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Apr 04 22:32:14 volumio systemd[1]: Startup finished in 9.356s (kernel) + 25.541s (userspace) = 34.897s.
Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: ############################
Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: # Volumio Streaming Daemon #
Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: # Running on port 7777 #
Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: ############################
Apr 04 22:32:15 volumio volumio-streaming-daemon[980]: Environment: production
Apr 04 22:32:15 volumio ntpd[701]: Listen normally on 4 eth0 169.254.197.80:123
Apr 04 22:32:15 volumio ntpd[701]: Listen normally on 5 wlan0 192.168.2.127:123
Apr 04 22:32:15 volumio ntpd[701]: new interface(s) found: waking up resolver
Apr 04 22:32:16 volumio volumio[979]: info: -------------------------------------------
Apr 04 22:32:16 volumio volumio[979]: info: ----- Volumio3 ----
Apr 04 22:32:16 volumio volumio[979]: info: -------------------------------------------
Apr 04 22:32:16 volumio volumio[979]: info: ----- System startup ----
Apr 04 22:32:16 volumio volumio[979]: info: -------------------------------------------
Apr 04 22:32:17 volumio volumio[979]: info: MYVOLUMIO Environment detected
Apr 04 22:32:17 volumio volumio[979]: info: Plugin folders cleanup
Apr 04 22:32:17 volumio volumio[979]: info: Scanning into folder /volumio/app/plugins/
Apr 04 22:32:17 volumio volumio[979]: info: Scanning category audio_interface
Apr 04 22:32:17 volumio volumio[979]: info: Scanning category miscellanea
Apr 04 22:32:17 volumio volumio[979]: info: Scanning category music_service
Apr 04 22:32:17 volumio volumio[979]: info: Scanning category plugins.json
Apr 04 22:32:17 volumio volumio[979]: info: Scanning category system_controller
Apr 04 22:32:17 volumio volumio[979]: info: Scanning category user_interface
Apr 04 22:32:17 volumio volumio[979]: info: Scanning into folder /data/plugins/
Apr 04 22:32:17 volumio volumio[979]: info: Scanning category music_service
Apr 04 22:32:17 volumio volumio[979]: info: Plugin folders cleanup completed
Apr 04 22:32:17 volumio volumio[979]: info: -------------------------------------------
Apr 04 22:32:17 volumio volumio[979]: info: ----- Core plugins startup ----
Apr 04 22:32:17 volumio volumio[979]: info: -------------------------------------------
Apr 04 22:32:17 volumio volumio[979]: info: Loading plugins from folder /volumio/app/plugins/
Apr 04 22:32:17 volumio volumio[979]: info: Adding plugin upnp to MyMusic Plugins
Apr 04 22:32:17 volumio volumio[979]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 04 22:32:17 volumio volumio[979]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 04 22:32:17 volumio volumio[979]: info: Loading plugins from folder /data/plugins/
Apr 04 22:32:17 volumio volumio[979]: info: Loading plugin "system"...
Apr 04 22:32:17 volumio volumio[979]: info: Loading plugin "appearance"...
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "network"...
Apr 04 22:32:18 volumio volumio[979]: info: Refreshing Cached IP Addresses
Apr 04 22:32:18 volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 04 22:32:18 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:18 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:18 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 04 22:32:18 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "services"...
Apr 04 22:32:18 volumio sudo[1006]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "alsa_controller"...
Apr 04 22:32:18 volumio sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 04 22:32:18 volumio sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "wizard"...
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "volumio_command_line_client"...
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "upnp"...
Apr 04 22:32:18 volumio volumio[979]: info: [1712269938608] Starting Upmpd Daemon
Apr 04 22:32:18 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "my_music"...
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "mpd"...
Apr 04 22:32:18 volumio volumio[979]: info: Creating MPD Configuration file
Apr 04 22:32:18 volumio volumio[979]: info: Loading plugin "upnp_browser"...
Apr 04 22:32:18 volumio sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 04 22:32:18 volumio sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:18 volumio sudo[1017]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:18 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 04 22:32:18 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:18 volumio systemd[1]: Stopping Music Player Daemon...
Apr 04 22:32:19 volumio systemd[1]: mpd.service: Succeeded.
Apr 04 22:32:19 volumio systemd[1]: Stopped Music Player Daemon.
Apr 04 22:32:19 volumio systemd[1]: Starting Music Player Daemon...
Apr 04 22:32:19 volumio volumio[979]: info: Loading plugin "networkfs"...
Apr 04 22:32:19 volumio volumio[979]: info: Starting Udev Watcher for removable devices
Apr 04 22:32:19 volumio mpd[1024]: Apr 04 22:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 04 22:32:19 volumio mpd[1024]: Apr 04 22:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Apr 04 22:32:19 volumio mpd[1024]: Apr 04 22:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Apr 04 22:32:19 volumio systemd[1]: Started Music Player Daemon.
Apr 04 22:32:19 volumio sudo[1019]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:19 volumio volumio[979]: info: Ignoring mount for partition: boot
Apr 04 22:32:19 volumio volumio[979]: info: Ignoring mount for partition: volumio
Apr 04 22:32:19 volumio volumio[979]: info: Ignoring mount for partition: volumio_data
Apr 04 22:32:19 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 04 22:32:19 volumio volumio[979]: info: Loading plugin "alarm-clock"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "airplay_emulation"...
Apr 04 22:32:20 volumio volumio[979]: info: Starting Shairport Sync
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "last_100"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "webradio"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "i2s_dacs"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "volumiodiscovery"...
Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 04 22:32:20 volumio node[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 04 22:32:20 volumio node[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** For more information see
Apr 04 22:32:20 volumio node[979]: *** WARNING *** For more information see
Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 04 22:32:20 volumio node[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 04 22:32:20 volumio node[979]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 04 22:32:20 volumio volumio[979]: *** WARNING *** For more information see
Apr 04 22:32:20 volumio node[979]: *** WARNING *** For more information see
Apr 04 22:32:20 volumio volumio[979]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 04 22:32:20 volumio volumio[979]: info: Discovery: Started advertising with name: Volumio
Apr 04 22:32:20 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "outputs"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "albumart"...
Apr 04 22:32:20 volumio volumio[979]: info: Plugin example_plugin is not enabled
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "inputs"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "updater_comm"...
Apr 04 22:32:20 volumio volumio[979]: info: Plugin mpdemulation is not enabled
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "rest_api"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "websocket"...
Apr 04 22:32:20 volumio volumio[979]: info: Loading plugin "nanosound_cd"...
Apr 04 22:32:20 volumio volumio[979]: Forking 3 albumart workers
Apr 04 22:32:21 volumio volumio[979]: Starting albumart workers
Apr 04 22:32:21 volumio volumio[979]: Starting albumart workers
Apr 04 22:32:21 volumio volumio[979]: Starting albumart workers
Apr 04 22:32:21 volumio volumio[979]: info: Applying required configuration parameters for plugin nanosound_cd
Apr 04 22:32:21 volumio volumio[979]: info: Loading i18n strings for locale de
Apr 04 22:32:21 volumio volumio[979]: Updating browse sources language
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::initPlayerControls
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: Express server listening on port 3000
Apr 04 22:32:21 volumio volumio[979]: [Metrics] WebUI: 5s 403.97ms
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::resetVolumioState
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::getcurrentVolume
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume
Apr 04 22:32:21 volumio volumio[979]: info: MPD Permissions set
Apr 04 22:32:21 volumio volumio[979]: verbose: New Socket.io Connection to 192.168.2.141 from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 1
Apr 04 22:32:21 volumio volumio[979]: verbose: New Socket.io Connection to 192.168.2.141 from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 2
Apr 04 22:32:21 volumio volumio[979]: info: MPD running with PID1024
Apr 04 22:32:21 volumio volumio[979]: ,establishing connection
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: Reloading queue from file
Apr 04 22:32:21 volumio volumio[979]: error: updateQueue error: null
Apr 04 22:32:21 volumio volumio[979]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Apr 04 22:32:21 volumio volumio[979]: info: VolumeController:: Volume=30 Mute =false
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::updateTrackBlock
Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrackBlock
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioRetrievevolume
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::setRepeat null single undefined
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::setRandom null
Apr 04 22:32:21 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:21 volumio volumio[979]: error: updateQueue error: null
Apr 04 22:32:21 volumio volumio[979]: info: Setting Device type: Raspberry PI
Apr 04 22:32:21 volumio volumio[979]: info: Completed loading Core Plugins
Apr 04 22:32:21 volumio volumio[979]: info: Preparing to generate the ALSA configuration file
Apr 04 22:32:21 volumio volumio[979]: info: Discovery: adding b46a5f31-d384-4637-bc1f-d3f244fd3af9
Apr 04 22:32:21 volumio volumio[979]: info: mDNS: Found device Volumio
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:21 volumio volumio[979]: info: Discovery: this is already registered, b46a5f31-d384-4637-bc1f-d3f244fd3af9
Apr 04 22:32:21 volumio volumio[979]: info: mDNS: Found device Volumio
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Apr 04 22:32:21 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:21 volumio volumio[979]: info: Asound.conf file unchanged, so no further update is needed
Apr 04 22:32:21 volumio volumio[979]: info: Output device has changed, restarting MPD
Apr 04 22:32:21 volumio sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 04 22:32:21 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:21 volumio volumio[979]: info: Output device has changed, restarting Shairport Sync
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:21 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:21 volumio sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 04 22:32:21 volumio sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:21 volumio systemd[1]: Stopping Music Player Daemon...
Apr 04 22:32:21 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 04 22:32:21 volumio volumio[979]: info: ___________ START PLUGINS ___________
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 04 22:32:21 volumio volumio[979]: info: [1712269941955] CoreMusicLibrary::Adding element Media Servers
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 04 22:32:21 volumio volumio[979]: Cannot find translation for sourceMedia Servers
Apr 04 22:32:21 volumio systemd[1]: mpd.service: Succeeded.
Apr 04 22:32:21 volumio systemd[1]: Stopped Music Player Daemon.
Apr 04 22:32:21 volumio systemd[1]: Starting Music Player Daemon...
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:21 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 04 22:32:22 volumio volumio[979]: info: [1712269942052] CoreMusicLibrary::Adding element Last_100
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceMedia Servers
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 04 22:32:22 volumio volumio[979]: info: [1712269942054] CoreMusicLibrary::Adding element Webradio
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceMedia Servers
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 04 22:32:22 volumio volumio[979]: info: [1712269942062] CoreMusicLibrary::Adding element NanoSound CD
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceMedia Servers
Apr 04 22:32:22 volumio volumio[979]: Cannot find translation for sourceNanoSound CD
Apr 04 22:32:22 volumio sudo[1009]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio volumio[979]: info: Volumio Calling Home
Apr 04 22:32:22 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosoundcd_progressweb
Apr 04 22:32:22 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:22 volumio systemd[1]: Started NanoSound CD Progress Web Service.
Apr 04 22:32:22 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio sudo[1125]: root : TTY=unknown ; PWD=/home/volumio/nanomesher_nanosoundcd ; USER=root ; COMMAND=/usr/bin/python -c import ripprogress_web; ripprogress_web.main()
Apr 04 22:32:22 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:22 volumio volumio[979]: info: MPD Permissions set
Apr 04 22:32:22 volumio volumio[979]: info: VolumeController:: Volume=30 Mute =false
Apr 04 22:32:22 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:22 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:22 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosoundcd_web
Apr 04 22:32:22 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:22 volumio volumio[979]: info: Volumio called home
Apr 04 22:32:22 volumio volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio systemd[1]: Started NanoSound CD Web Service.
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:22 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:22 volumio sudo[1136]: root : TTY=unknown ; PWD=/home/volumio/nanomesher_nanosoundcd ; USER=root ; COMMAND=/usr/bin/python -c import nanosoundcd_web; nanosoundcd_web.main()
Apr 04 22:32:22 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:22 volumio volumio[979]: info: NanoSound CD daemon started
Apr 04 22:32:22 volumio volumio[979]: info: Completed starting Core Plugins
Apr 04 22:32:22 volumio volumio[979]: info: -------------------------------------------
Apr 04 22:32:22 volumio volumio[979]: info: ----- MyVolumio plugins startup ----
Apr 04 22:32:22 volumio volumio[979]: info: -------------------------------------------
Apr 04 22:32:22 volumio volumio[979]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 04 22:32:22 volumio volumio[979]: info: Starting Shairport Sync
Apr 04 22:32:22 volumio volumio[979]: info: Starting Shairport Sync
Apr 04 22:32:22 volumio sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 04 22:32:22 volumio sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:22 volumio volumio[979]: info: Starting Shairport Sync
Apr 04 22:32:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 04 22:32:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 04 22:32:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 04 22:32:22 volumio sudo[1140]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 04 22:32:22 volumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 04 22:32:22 volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:22 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:22 volumio volumio-remote-updater[548]: [2024-04-04 22:32:22] [connect] Successful connection
Apr 04 22:32:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 04 22:32:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 04 22:32:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 04 22:32:22 volumio sudo[1145]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Apr 04 22:32:22 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 04 22:32:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 04 22:32:22 volumio volumio[979]: info: Shairport-Sync Started
Apr 04 22:32:22 volumio volumio[979]: Error adding Membership: Error: addMembership EINVAL
Apr 04 22:32:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 04 22:32:22 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio volumio[979]: info: Shairport-Sync Started
Apr 04 22:32:22 volumio volumio-remote-updater[548]: [2024-04-04 22:32:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712269942 101
Apr 04 22:32:22 volumio volumio[979]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4
Apr 04 22:32:22 volumio volumio[979]: info: Shairport-Sync Started
Apr 04 22:32:22 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Apr 04 22:32:22 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_progressweb.service: Main process exited, code=exited, status=1/FAILURE
Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_progressweb.service: Failed with result 'exit-code'.
Apr 04 22:32:22 volumio mpd[1120]: Apr 04 22:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 04 22:32:22 volumio mpd[1120]: Apr 04 22:32 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Apr 04 22:32:22 volumio mpd[1120]: Apr 04 22:32 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Apr 04 22:32:22 volumio systemd[1]: Started Music Player Daemon.
Apr 04 22:32:22 volumio sudo[1105]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio volumio[979]: error: updateQueue error: null
Apr 04 22:32:22 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_web.service: Main process exited, code=exited, status=1/FAILURE
Apr 04 22:32:22 volumio systemd[1]: nanosoundcd_web.service: Failed with result 'exit-code'.
Apr 04 22:32:23 volumio volumio[979]: verbose: New Socket.io Connection to volumio.local from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5
Apr 04 22:32:23 volumio volumio[979]: verbose: New Socket.io Connection to volumio.local from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 6
Apr 04 22:32:24 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Apr 04 22:32:24 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:24 volumio volumio[979]: info: CoreCommandRouter::volumioGetQueue
Apr 04 22:32:24 volumio volumio[979]: info: CoreStateMachine::getQueue
Apr 04 22:32:24 volumio volumio[979]: info: CorePlayQueue::getQueue
Apr 04 22:32:24 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Apr 04 22:32:25 volumio volumio[979]: verbose: New Socket.io Connection to 192.168.2.141 from 192.168.2.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 7
Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::volumioGetState
Apr 04 22:32:25 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 04 22:32:25 volumio volumio[979]: info: CoreCommandRouter::volumioGetQueue
Apr 04 22:32:25 volumio volumio[979]: info: CoreStateMachine::getQueue
Apr 04 22:32:25 volumio volumio[979]: info: CorePlayQueue::getQueue
Apr 04 22:32:25 volumio volumio[979]: info: Listing playlists
Apr 04 22:32:25 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Apr 04 22:32:28 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 04 22:32:28 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:28 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:28 volumio sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 04 22:32:28 volumio sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:28 volumio sudo[1161]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:28 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 04 22:32:28 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:28 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 04 22:32:28 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:28 volumio volumio[979]: info: Upmpdcli Daemon Started
Apr 04 22:32:28 volumio volumio[1167]: Generating RSA private key, 4096 bit long modulus (2 primes)
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin multiroom to MyMusic Plugins
Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 04 22:32:30 volumio volumio[979]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 04 22:32:30 volumio volumio[979]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 04 22:32:31 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:31 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:31 volumio volumio[979]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 04 22:32:31 volumio volumio[979]: info: MyVolumio not started
Apr 04 22:32:31 volumio volumio[979]: info: Initializing device activation check
Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 04 22:32:31 volumio volumio[979]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 04 22:32:31 volumio volumio[979]: info: Streaming services startup
Apr 04 22:32:31 volumio volumio[979]: info: Starting Streaming Daemon
Apr 04 22:32:31 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 04 22:32:31 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 04 22:32:31 volumio systemd[1]: Stopping Volumio Streaming Daemon...
Apr 04 22:32:31 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 04 22:32:31 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded.
Apr 04 22:32:31 volumio systemd[1]: Stopped Volumio Streaming Daemon.
Apr 04 22:32:31 volumio systemd[1]: Started Volumio Streaming Daemon.
Apr 04 22:32:31 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root
Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: ############################
Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: # Volumio Streaming Daemon #
Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: # Running on port 7777 #
Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: ############################
Apr 04 22:32:31 volumio volumio-streaming-daemon[1189]: Environment: production
Apr 04 22:32:32 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Apr 04 22:32:34 volumio volumio-streaming-daemon[1189]: Environment: production
Apr 04 22:32:34 volumio volumio[979]: info: Fetching Streaming Services browse cache
Apr 04 22:32:34 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Apr 04 22:32:35 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Apr 04 22:32:35 volumio volumio[979]: info: TuneIn handleBrowseUri: tunein/local
Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::ClearQueue
Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::stop
Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 04 22:32:37 volumio volumio[979]: verbose: UNSET VOLATILE
Apr 04 22:32:37 volumio volumio[979]: info: CorePlayQueue::clearPlayQueue
Apr 04 22:32:37 volumio volumio[979]: info: CorePlayQueue::saveQueue
Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::volumioPushQueue
Apr 04 22:32:37 volumio volumio[979]: info: CoreStateMachine::addQueueItems
Apr 04 22:32:37 volumio volumio[979]: info: CorePlayQueue::addQueueItems
Apr 04 22:32:37 volumio volumio[979]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25260
Apr 04 22:32:37 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::saveQueue
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushQueue
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::updateTrackBlock
Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrackBlock
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPlay
Apr 04 22:32:38 volumio volumio[979]: verbose: UNSET VOLATILE
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::play index 0
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::stop
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 04 22:32:38 volumio volumio[979]: verbose: UNSET VOLATILE
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::play index undefined
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::startPlaybackTimer
Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:38 volumio volumio[979]: info: [1712269958009] ControllerWebradio::clearAddPlayTrack
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand stop
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand stop took 3 milliseconds
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand clear
Apr 04 22:32:38 volumio volumio[979]: info:
Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces system playlist update
Apr 04 22:32:38 volumio volumio[979]: info: Ignoring MPD Status Update
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand clear took 2 milliseconds
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand load "https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein"
Apr 04 22:32:38 volumio volumio[979]: error: updateQueue error: null
Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 5ms
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand add "https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein"
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand add "https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein" took 0 milliseconds
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand play
Apr 04 22:32:38 volumio volumio[979]: info:
Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces system playlist update
Apr 04 22:32:38 volumio volumio[979]: info: Ignoring MPD Status Update
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand play took 4 milliseconds
Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 3ms
Apr 04 22:32:38 volumio volumio[979]: info:
Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces state update: player
Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::getState
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status
Apr 04 22:32:38 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand status took 20 milliseconds
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseState
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 04 22:32:38 volumio volumio[979]: info:
Apr 04 22:32:38 volumio volumio[979]: ---------------------------- MPD announces state update: player
Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::getState
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand status took 1 milliseconds
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseState
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::pushState
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::servicePushState
Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:38 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Apr 04 22:32:38 volumio volumio[979]: verbose: CURRENT POSITION 0
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState stateService play
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus stop
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 37ms
Apr 04 22:32:38 volumio volumio[979]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 04 22:32:38 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo
Apr 04 22:32:38 volumio volumio[979]: info: ControllerMpd::pushState
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::servicePushState
Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:38 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Apr 04 22:32:38 volumio volumio[979]: verbose: CURRENT POSITION 0
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState stateService play
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play
Apr 04 22:32:38 volumio volumio[979]: info: Received an update from plugin. extracting info from payload
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:38 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:38 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:38 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:38 volumio volumio[979]: info: ------------------------------ 28ms
Apr 04 22:32:39 volumio volumio[979]: info:
Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces system playlist update
Apr 04 22:32:39 volumio volumio[979]: info: Ignoring MPD Status Update
Apr 04 22:32:39 volumio volumio[979]: info:
Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces state update: player
Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::getState
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status
Apr 04 22:32:39 volumio volumio[979]: info:
Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces system playlist update
Apr 04 22:32:39 volumio volumio[979]: info: Ignoring MPD Status Update
Apr 04 22:32:39 volumio volumio[979]: info:
Apr 04 22:32:39 volumio volumio[979]: ---------------------------- MPD announces state update: player
Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 4ms
Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand status took 3 milliseconds
Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::getState
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseState
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 4ms
Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand status took 3 milliseconds
Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseState
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo
Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::pushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::servicePushState
Apr 04 22:32:39 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:39 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Die junge Nacht der ARD","artist":"1Live, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Apr 04 22:32:39 volumio volumio[979]: verbose: CURRENT POSITION 0
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState stateService play
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play
Apr 04 22:32:39 volumio volumio[979]: info: Received an update from plugin. extracting info from payload
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 19ms
Apr 04 22:32:39 volumio volumio[979]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 04 22:32:39 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo
Apr 04 22:32:39 volumio volumio[979]: info: ControllerMpd::pushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::servicePushState
Apr 04 22:32:39 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:39 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Die junge Nacht der ARD","artist":"1Live, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Apr 04 22:32:39 volumio volumio[979]: verbose: CURRENT POSITION 0
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState stateService play
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play
Apr 04 22:32:39 volumio volumio[979]: info: Received an update from plugin. extracting info from payload
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:39 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:39 volumio volumio[979]: info: ------------------------------ 29ms
Apr 04 22:32:41 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: nanosound_cd , handleBrowseUri
Apr 04 22:32:45 volumio volumio[1167]: ......................................................................................................................................................................................++++
Apr 04 22:32:51 volumio volumio[979]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Apr 04 22:32:51 volumio volumio[979]: info: Completed starting MyVolumio Plugin
Apr 04 22:32:51 volumio volumio[979]: info: BOOT COMPLETED
Apr 04 22:32:51 volumio volumio[979]: [Metrics] CommandRouter: 35s 258.49ms
Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:51 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent
Apr 04 22:32:51 volumio volumio[979]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
Apr 04 22:32:51 volumio volumio[979]: aplay: main:828: audio open error: Device or resource busy
Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 04 22:32:52 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 04 22:32:55 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 04 22:32:56 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent
Apr 04 22:32:59 volumio volumio[979]: info:
Apr 04 22:32:59 volumio volumio[979]: ---------------------------- MPD announces system playlist update
Apr 04 22:32:59 volumio volumio[979]: info: Ignoring MPD Status Update
Apr 04 22:32:59 volumio volumio[979]: info:
Apr 04 22:32:59 volumio volumio[979]: ---------------------------- MPD announces state update: player
Apr 04 22:32:59 volumio volumio[979]: info: ControllerMpd::getState
Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand status
Apr 04 22:32:59 volumio volumio[979]: info: ------------------------------ 3ms
Apr 04 22:32:59 volumio volumio[979]: info: sendMpdCommand status took 2 milliseconds
Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::parseState
Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 04 22:32:59 volumio volumio[979]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 04 22:32:59 volumio volumio[979]: verbose: ControllerMpd::parseTrackInfo
Apr 04 22:32:59 volumio volumio[979]: info: ControllerMpd::pushState
Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::servicePushState
Apr 04 22:32:59 volumio volumio[979]: info: CorePlayQueue::getTrack 0
Apr 04 22:32:59 volumio volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21112,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Teddy Swims - Lose Control","artist":"1Live, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-1live-live.icecastssl.wdr.de/wdr/1live/live/mp3/128/stream.mp3?aggregator=tunein","trackType":""}
Apr 04 22:32:59 volumio volumio[979]: verbose: CURRENT POSITION 0
Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::syncState stateService play
Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::syncState currentStatus play
Apr 04 22:32:59 volumio volumio[979]: info: Received an update from plugin. extracting info from payload
Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:59 volumio volumio[979]: info: CoreStateMachine::pushState
Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::volumioPushState
Apr 04 22:32:59 volumio volumio[979]: info: ------------------------------ 18ms
Apr 04 22:32:59 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 04 22:33:05 volumio ntpd[701]: Soliciting pool server 195.201.20.16
Apr 04 22:33:05 volumio ntpd[701]: Soliciting pool server 178.63.166.157
Apr 04 22:33:06 volumio ntpd[701]: Soliciting pool server 168.119.238.107
Apr 04 22:33:06 volumio ntpd[701]: Soliciting pool server 176.9.44.212
Apr 04 22:33:07 volumio ntpd[701]: Soliciting pool server 167.235.246.170
Apr 04 22:33:07 volumio ntpd[701]: Soliciting pool server 162.159.200.123
Apr 04 22:33:08 volumio ntpd[701]: Soliciting pool server 89.58.51.72
Apr 04 22:33:08 volumio ntpd[701]: Soliciting pool server 88.218.226.91
Apr 04 22:33:08 volumio ntpd[701]: Soliciting pool server 193.158.22.13
Apr 04 22:33:09 volumio ntpd[701]: Soliciting pool server 162.159.200.1
Apr 04 22:33:09 volumio volumio[1167]: ....................................................................................................................................................................................................................................................................................++++
Apr 04 22:33:09 volumio volumio[1167]: e is 65537 (0x010001)
Apr 04 22:33:10 volumio volumio[1167]: writing RSA key
Apr 04 22:33:10 volumio ntpd[701]: Soliciting pool server 157.90.24.29
Apr 04 22:33:10 volumio ntpd[701]: Soliciting pool server 193.203.3.171
Apr 04 22:33:11 volumio ntpd[701]: Soliciting pool server 144.91.116.85
Apr 04 22:33:11 volumio ntpd[701]: Soliciting pool server 88.99.76.254
Apr 04 22:33:12 volumio ntpd[701]: Soliciting pool server 144.76.66.156
Apr 04 22:33:12 volumio ntpd[701]: Soliciting pool server 2a0e:b107:27d0:1::6
Apr 04 22:33:13 volumio ntpd[701]: Soliciting pool server 89.238.82.145
Apr 04 22:33:50 volumio volumio[979]: info: CALLMETHOD: music_service nanosound_cd saveReg [object Object]
Apr 04 22:33:50 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: nanosound_cd , saveReg
Apr 04 22:33:50 volumio volumio[979]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 04 22:33:50 volumio volumio[979]: TypeError: Cannot read property 'response' of undefined
Apr 04 22:33:50 volumio volumio[979]: at Request._callback (/data/plugins/music_service/nanosound_cd/index.js:40:10)
Apr 04 22:33:50 volumio volumio[979]: at self.callback (/data/plugins/music_service/nanosound_cd/node_modules/request/request.js:185:22)
Apr 04 22:33:50 volumio volumio[979]: at Request.emit (events.js:315:20)
Apr 04 22:33:50 volumio volumio[979]: at Request.onRequestError (/data/plugins/music_service/nanosound_cd/node_modules/request/request.js:877:8)
Apr 04 22:33:50 volumio volumio[979]: at ClientRequest.emit (events.js:315:20)
Apr 04 22:33:50 volumio volumio[979]: at Socket.socketErrorListener (_http_client.js:469:9)
Apr 04 22:33:50 volumio volumio[979]: at Socket.emit (events.js:315:20)
Apr 04 22:33:50 volumio volumio[979]: at emitErrorNT (internal/streams/destroy.js:106:8)
Apr 04 22:33:50 volumio volumio[979]: at emitErrorCloseNT (internal/streams/destroy.js:74:3)
Apr 04 22:33:50 volumio volumio[979]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Apr 04 22:33:50 volumio volumio[979]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 04 22:33:51 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-04 22:32
Apr 04 22:33:51 volumio sudo[1326]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b"
VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9"
VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1"
VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST"
VOLUMIO_VERSION="3.251"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"